builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-035 starttime: 1448332178.33 results: success (0) buildid: 20151123173004 builduid: 08e1d62f389c4ada9a7a09a24c31c420 revision: 65cd2c7696e438c74bfbb0ad40e18f411dbead38 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.327608) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.327975) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.328260) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006636 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.349431) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.349705) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.368039) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.368295) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.027277 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.427354) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.427634) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.427979) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.428269) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-23 18:29:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 39.0M=0s 2015-11-23 18:29:39 (39.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.202233 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.644584) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.645192) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.087394 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.743995) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:38.744297) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 65cd2c7696e438c74bfbb0ad40e18f411dbead38 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 65cd2c7696e438c74bfbb0ad40e18f411dbead38 --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=0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-23 18:29:39,202 truncating revision to first 12 chars 2015-11-23 18:29:39,203 Setting DEBUG logging. 2015-11-23 18:29:39,203 attempt 1/10 2015-11-23 18:29:39,203 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/65cd2c7696e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 18:29:39,424 unpacking tar archive at: fx-team-65cd2c7696e4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.494294 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.252680) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.253031) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.261260) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.261615) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:29:39.262076) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-11-23 18:29:39.262406) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:29:39 INFO - MultiFileLogger online at 20151123 18:29:39 in /builds/slave/test-pgo 18:29:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:29:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:29:39 INFO - {'append_to_log': False, 18:29:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:29:39 INFO - 'blob_upload_branch': 'Fx-Team', 18:29:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:29:39 INFO - 'branch': 'Fx-Team', 18:29:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:39 INFO - 'config_files': ('talos/linux_config.py',), 18:29:39 INFO - 'default_actions': ('clobber', 18:29:39 INFO - 'read-buildbot-config', 18:29:39 INFO - 'download-and-extract', 18:29:39 INFO - 'populate-webroot', 18:29:39 INFO - 'create-virtualenv', 18:29:39 INFO - 'install', 18:29:39 INFO - 'run-tests'), 18:29:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:39 INFO - 'download_minidump_stackwalk': True, 18:29:39 INFO - 'download_symbols': 'ondemand', 18:29:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:39 INFO - 'installer_path': 'installer.exe', 18:29:39 INFO - 'log_level': 'info', 18:29:39 INFO - 'log_name': 'talos', 18:29:39 INFO - 'log_to_console': True, 18:29:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:29:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:29:39 INFO - 'opt_config_files': (), 18:29:39 INFO - 'pip_index': False, 18:29:39 INFO - 'sps_profile': False, 18:29:39 INFO - 'sps_profile_interval': 0, 18:29:39 INFO - 'suite': 'dromaeojs-e10s', 18:29:39 INFO - 'system_bits': '64', 18:29:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:39 INFO - 'title': 'talos-linux64-ix-035', 18:29:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:39 INFO - 'use_talos_json': True, 18:29:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:29:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:39 INFO - 'work_dir': 'build'} 18:29:39 INFO - ##### 18:29:39 INFO - ##### Running clobber step. 18:29:39 INFO - ##### 18:29:39 INFO - Running pre-action listener: _resource_record_pre_action 18:29:39 INFO - Running main action method: clobber 18:29:39 INFO - rmtree: /builds/slave/test-pgo/build 18:29:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:29:40 INFO - Running post-action listener: _resource_record_post_action 18:29:40 INFO - ##### 18:29:40 INFO - ##### Running read-buildbot-config step. 18:29:40 INFO - ##### 18:29:40 INFO - Running pre-action listener: _resource_record_pre_action 18:29:40 INFO - Running main action method: read_buildbot_config 18:29:40 INFO - Using buildbot properties: 18:29:40 INFO - { 18:29:40 INFO - "properties": { 18:29:40 INFO - "buildnumber": 353, 18:29:40 INFO - "product": "firefox", 18:29:40 INFO - "script_repo_revision": "production", 18:29:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 18:29:40 INFO - "repository": "", 18:29:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 18:29:40 INFO - "buildid": "20151123173004", 18:29:40 INFO - "slavename": "talos-linux64-ix-035", 18:29:40 INFO - "pgo_build": "True", 18:29:40 INFO - "basedir": "/builds/slave/test-pgo", 18:29:40 INFO - "project": "", 18:29:40 INFO - "platform": "ubuntu64_hw", 18:29:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:29:40 INFO - "slavebuilddir": "test", 18:29:40 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 18:29:40 INFO - "branch": "fx-team", 18:29:40 INFO - "repo_path": "integration/fx-team", 18:29:40 INFO - "stage_platform": "linux64-pgo", 18:29:40 INFO - "builduid": "08e1d62f389c4ada9a7a09a24c31c420", 18:29:40 INFO - "revision": "65cd2c7696e438c74bfbb0ad40e18f411dbead38" 18:29:40 INFO - }, 18:29:40 INFO - "sourcestamp": { 18:29:40 INFO - "repository": "", 18:29:40 INFO - "hasPatch": false, 18:29:40 INFO - "project": "", 18:29:40 INFO - "branch": "fx-team-linux64-pgo-talos", 18:29:40 INFO - "changes": [ 18:29:40 INFO - { 18:29:40 INFO - "category": null, 18:29:40 INFO - "files": [ 18:29:40 INFO - { 18:29:40 INFO - "url": null, 18:29:40 INFO - "name": "https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:29:40 INFO - } 18:29:40 INFO - ], 18:29:40 INFO - "repository": "", 18:29:40 INFO - "rev": "65cd2c7696e438c74bfbb0ad40e18f411dbead38", 18:29:40 INFO - "who": "sendchange", 18:29:40 INFO - "when": 1448332155, 18:29:40 INFO - "number": 6698511, 18:29:40 INFO - "comments": "", 18:29:40 INFO - "project": "", 18:29:40 INFO - "at": "Mon 23 Nov 2015 18:29:15", 18:29:40 INFO - "branch": "fx-team-linux64-pgo-talos", 18:29:40 INFO - "revlink": "", 18:29:40 INFO - "properties": [ 18:29:40 INFO - [ 18:29:40 INFO - "buildid", 18:29:40 INFO - "20151123173004", 18:29:40 INFO - "Change" 18:29:40 INFO - ], 18:29:40 INFO - [ 18:29:40 INFO - "builduid", 18:29:40 INFO - "08e1d62f389c4ada9a7a09a24c31c420", 18:29:40 INFO - "Change" 18:29:40 INFO - ], 18:29:40 INFO - [ 18:29:40 INFO - "pgo_build", 18:29:40 INFO - "True", 18:29:40 INFO - "Change" 18:29:40 INFO - ] 18:29:40 INFO - ], 18:29:40 INFO - "revision": "65cd2c7696e438c74bfbb0ad40e18f411dbead38" 18:29:40 INFO - } 18:29:40 INFO - ], 18:29:40 INFO - "revision": "65cd2c7696e438c74bfbb0ad40e18f411dbead38" 18:29:40 INFO - } 18:29:40 INFO - } 18:29:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:29:40 INFO - Running post-action listener: _resource_record_post_action 18:29:40 INFO - ##### 18:29:40 INFO - ##### Running download-and-extract step. 18:29:40 INFO - ##### 18:29:40 INFO - Running pre-action listener: _resource_record_pre_action 18:29:40 INFO - Running main action method: download_and_extract 18:29:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:40 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:29:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json 18:29:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json 18:29:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:29:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:29:44 INFO - Downloaded 1302 bytes. 18:29:44 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:29:44 INFO - Using the following test package requirements: 18:29:44 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:29:44 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'jsshell-linux-x86_64.zip'], 18:29:44 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:29:44 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:29:44 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:29:44 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:29:44 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:44 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:44 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:29:44 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:44 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:29:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:47 INFO - Downloaded 22526486 bytes. 18:29:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:29:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:29:48 INFO - Return code: 0 18:29:48 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:48 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:29:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:59 INFO - Downloaded 22526486 bytes. 18:29:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:29:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:29:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:30:00 INFO - Return code: 0 18:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:00 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:30:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:30:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:30:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:30:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:30:02 INFO - Downloaded 11130997 bytes. 18:30:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:30:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:30:02 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:30:03 INFO - Return code: 0 18:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:03 INFO - https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:30:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:30:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:30:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:30:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:30:04 INFO - Downloaded 57581985 bytes. 18:30:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:30:04 INFO - mkdir: /builds/slave/test-pgo/properties 18:30:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:30:04 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:30:04 INFO - Contents: 18:30:04 INFO - build_url:https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:30:04 INFO - Running post-action listener: _resource_record_post_action 18:30:04 INFO - Running post-action listener: set_extra_try_arguments 18:30:04 INFO - ##### 18:30:04 INFO - ##### Running populate-webroot step. 18:30:04 INFO - ##### 18:30:04 INFO - Running pre-action listener: _resource_record_pre_action 18:30:04 INFO - Running main action method: populate_webroot 18:30:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:30:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:30:04 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:30:04 INFO - '../fennec_ids.txt'], 18:30:04 INFO - 'tests': ['tcheck2']}, 18:30:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:30:04 INFO - '--tppagecycles', 18:30:04 INFO - '7'], 18:30:04 INFO - 'tests': ['tsvgm']}}, 18:30:04 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:30:04 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:30:04 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:30:04 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:30:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:04 INFO - 'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:04 INFO - 'tests': ['damp', 'tps']}, 18:30:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:04 INFO - 'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['damp', 'tps']}, 18:30:04 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:30:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:30:04 INFO - 'other': {'tests': ['a11yr', 18:30:04 INFO - 'ts_paint', 18:30:04 INFO - 'tpaint', 18:30:04 INFO - 'sessionrestore', 18:30:04 INFO - 'sessionrestore_no_auto_restore']}, 18:30:04 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['a11yr', 18:30:04 INFO - 'ts_paint', 18:30:04 INFO - 'tpaint', 18:30:04 INFO - 'sessionrestore', 18:30:04 INFO - 'sessionrestore_no_auto_restore']}, 18:30:04 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['a11yr', 18:30:04 INFO - 'ts_paint', 18:30:04 INFO - 'tpaint', 18:30:04 INFO - 'sessionrestore', 18:30:04 INFO - 'sessionrestore_no_auto_restore']}, 18:30:04 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['a11yr', 18:30:04 INFO - 'ts_paint', 18:30:04 INFO - 'tpaint', 18:30:04 INFO - 'sessionrestore', 18:30:04 INFO - 'sessionrestore_no_auto_restore']}, 18:30:04 INFO - 'other_l64': {'tests': ['a11yr', 18:30:04 INFO - 'ts_paint', 18:30:04 INFO - 'tpaint', 18:30:04 INFO - 'sessionrestore', 18:30:04 INFO - 'sessionrestore_no_auto_restore']}, 18:30:04 INFO - 'other_nol64': {'tests': ['a11yr', 18:30:04 INFO - 'ts_paint', 18:30:04 INFO - 'tpaint', 18:30:04 INFO - 'sessionrestore', 18:30:04 INFO - 'sessionrestore_no_auto_restore']}, 18:30:04 INFO - 'svgr': {'tests': ['tsvgx', 18:30:04 INFO - 'tsvgr_opacity', 18:30:04 INFO - 'tart', 18:30:04 INFO - 'tscrollx', 18:30:04 INFO - 'cart']}, 18:30:04 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['tsvgx', 18:30:04 INFO - 'tsvgr_opacity', 18:30:04 INFO - 'tart', 18:30:04 INFO - 'tscrollx', 18:30:04 INFO - 'cart']}, 18:30:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:04 INFO - 'tests': ['tp5o']}, 18:30:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:04 INFO - 'talos_options': ['--e10s'], 18:30:04 INFO - 'tests': ['tp5o']}, 18:30:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:04 INFO - 'talos_options': ['--xperf_path', 18:30:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:04 INFO - 'tests': ['tp5n']}, 18:30:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:04 INFO - 'talos_options': ['--e10s', 18:30:04 INFO - '--xperf_path', 18:30:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:04 INFO - 'tests': ['tp5n']}}, 18:30:04 INFO - 'talos.zip': {'path': '', 18:30:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:30:04 INFO - Running post-action listener: _resource_record_post_action 18:30:04 INFO - ##### 18:30:04 INFO - ##### Running create-virtualenv step. 18:30:04 INFO - ##### 18:30:04 INFO - Running pre-action listener: _resource_record_pre_action 18:30:04 INFO - Running main action method: create_virtualenv 18:30:04 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:30:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 18:30:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:30:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:30:04 INFO - Using real prefix '/usr' 18:30:04 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:30:05 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:06 INFO - Installing pip.................done. 18:30:06 INFO - Return code: 0 18:30:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:06 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 0x7fa487375c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd1d6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe13d70>, '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 0xe14b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe14fb0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:30:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:30:06 INFO - Using env: {'DISPLAY': ':0', 18:30:06 INFO - 'HOME': '/home/cltbld', 18:30:06 INFO - 'LANG': 'en_US.UTF-8', 18:30:06 INFO - 'LANGUAGE': 'en_US:en', 18:30:06 INFO - 'LOGNAME': 'cltbld', 18:30:06 INFO - 'MAIL': '/var/mail/cltbld', 18:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:06 INFO - 'MOZ_NO_REMOTE': '1', 18:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:06 INFO - 'NO_EM_RESTART': '1', 18:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:07 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:07 INFO - 'SHELL': '/bin/bash', 18:30:07 INFO - 'SHLVL': '1', 18:30:07 INFO - 'TERM': 'linux', 18:30:07 INFO - 'TMOUT': '86400', 18:30:07 INFO - 'USER': 'cltbld', 18:30:07 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 18:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:07 INFO - '_': '/tools/buildbot/bin/python'} 18:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:30:07 INFO - Cleaning up... 18:30:07 INFO - Return code: 0 18:30:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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', '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 0x7fa487375c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd1d6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe13d70>, '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 0xe14b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe14fb0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 18:30: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 psutil>=0.7.1 18:30:07 INFO - Using env: {'DISPLAY': ':0', 18:30:07 INFO - 'HOME': '/home/cltbld', 18:30:07 INFO - 'LANG': 'en_US.UTF-8', 18:30:07 INFO - 'LANGUAGE': 'en_US:en', 18:30:07 INFO - 'LOGNAME': 'cltbld', 18:30:07 INFO - 'MAIL': '/var/mail/cltbld', 18:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:07 INFO - 'MOZ_NO_REMOTE': '1', 18:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:07 INFO - 'NO_EM_RESTART': '1', 18:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:07 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:07 INFO - 'SHELL': '/bin/bash', 18:30:07 INFO - 'SHLVL': '1', 18:30:07 INFO - 'TERM': 'linux', 18:30:07 INFO - 'TMOUT': '86400', 18:30:07 INFO - 'USER': 'cltbld', 18:30:07 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 18:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:07 INFO - '_': '/tools/buildbot/bin/python'} 18:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:07 INFO - Downloading/unpacking psutil>=0.7.1 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:09 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:30:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:30:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:09 INFO - Installing collected packages: psutil 18:30:09 INFO - Running setup.py install for psutil 18:30:09 INFO - building 'psutil._psutil_linux' extension 18:30:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:30:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:30:09 INFO - building 'psutil._psutil_posix' extension 18:30:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:30:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:30:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:09 INFO - Successfully installed psutil 18:30:09 INFO - Cleaning up... 18:30:09 INFO - Return code: 0 18:30:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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', '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 0x7fa487375c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd1d6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe13d70>, '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 0xe14b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe14fb0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:30: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 mozsystemmonitor==0.0.0 18:30:09 INFO - Using env: {'DISPLAY': ':0', 18:30:09 INFO - 'HOME': '/home/cltbld', 18:30:09 INFO - 'LANG': 'en_US.UTF-8', 18:30:09 INFO - 'LANGUAGE': 'en_US:en', 18:30:09 INFO - 'LOGNAME': 'cltbld', 18:30:09 INFO - 'MAIL': '/var/mail/cltbld', 18:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:09 INFO - 'MOZ_NO_REMOTE': '1', 18:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:09 INFO - 'NO_EM_RESTART': '1', 18:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:09 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:09 INFO - 'SHELL': '/bin/bash', 18:30:09 INFO - 'SHLVL': '1', 18:30:09 INFO - 'TERM': 'linux', 18:30:09 INFO - 'TMOUT': '86400', 18:30:09 INFO - 'USER': 'cltbld', 18:30:09 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 18:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:09 INFO - '_': '/tools/buildbot/bin/python'} 18:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:30:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:30:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:30:11 INFO - Installing collected packages: mozsystemmonitor 18:30:11 INFO - Running setup.py install for mozsystemmonitor 18:30:11 INFO - Successfully installed mozsystemmonitor 18:30:11 INFO - Cleaning up... 18:30:11 INFO - Return code: 0 18:30:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:11 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 0x7fa487375c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd1d6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe13d70>, '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 0xe14b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe14fb0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 18:30:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:30:11 INFO - Using env: {'DISPLAY': ':0', 18:30:11 INFO - 'HOME': '/home/cltbld', 18:30:11 INFO - 'LANG': 'en_US.UTF-8', 18:30:11 INFO - 'LANGUAGE': 'en_US:en', 18:30:11 INFO - 'LOGNAME': 'cltbld', 18:30:11 INFO - 'MAIL': '/var/mail/cltbld', 18:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:11 INFO - 'MOZ_NO_REMOTE': '1', 18:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:11 INFO - 'NO_EM_RESTART': '1', 18:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:11 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:11 INFO - 'SHELL': '/bin/bash', 18:30:11 INFO - 'SHLVL': '1', 18:30:11 INFO - 'TERM': 'linux', 18:30:11 INFO - 'TMOUT': '86400', 18:30:11 INFO - 'USER': 'cltbld', 18:30:11 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 18:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:11 INFO - '_': '/tools/buildbot/bin/python'} 18:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:12 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:13 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:30:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:30:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:30:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:14 INFO - Downloading docopt-0.6.1.tar.gz 18:30:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:30:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:14 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:14 INFO - Running setup.py install for blobuploader 18:30:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:14 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:30:14 INFO - Running setup.py install for requests 18:30:14 INFO - Running setup.py install for docopt 18:30:14 INFO - Successfully installed blobuploader requests docopt 18:30:14 INFO - Cleaning up... 18:30:15 INFO - Return code: 0 18:30:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:15 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 0x7fa487375c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd1d6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe13d70>, '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 0xe14b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe14fb0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:30:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:15 INFO - Using env: {'DISPLAY': ':0', 18:30:15 INFO - 'HOME': '/home/cltbld', 18:30:15 INFO - 'LANG': 'en_US.UTF-8', 18:30:15 INFO - 'LANGUAGE': 'en_US:en', 18:30:15 INFO - 'LOGNAME': 'cltbld', 18:30:15 INFO - 'MAIL': '/var/mail/cltbld', 18:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:15 INFO - 'MOZ_NO_REMOTE': '1', 18:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:15 INFO - 'NO_EM_RESTART': '1', 18:30:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:15 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:15 INFO - 'SHELL': '/bin/bash', 18:30:15 INFO - 'SHLVL': '1', 18:30:15 INFO - 'TERM': 'linux', 18:30:15 INFO - 'TMOUT': '86400', 18:30:15 INFO - 'USER': 'cltbld', 18:30:15 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 18:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:15 INFO - '_': '/tools/buildbot/bin/python'} 18:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:15 INFO - Running setup.py (path:/tmp/pip-ER0DSe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:15 INFO - Running setup.py (path:/tmp/pip-wmn7ZT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:15 INFO - Running setup.py (path:/tmp/pip-SNL7rq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:15 INFO - Running setup.py (path:/tmp/pip-giDOB_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:15 INFO - Running setup.py (path:/tmp/pip-4cMryw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:15 INFO - Running setup.py (path:/tmp/pip-pnSUQP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:15 INFO - Running setup.py (path:/tmp/pip-PpY8lA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:15 INFO - Running setup.py (path:/tmp/pip-eghhZB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:15 INFO - Running setup.py (path:/tmp/pip-QT7zvG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:15 INFO - Running setup.py (path:/tmp/pip-wlB9S1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:15 INFO - Running setup.py (path:/tmp/pip-8jvvk3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:16 INFO - Running setup.py (path:/tmp/pip-Rjte5M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:16 INFO - Running setup.py (path:/tmp/pip-0ehT3k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:16 INFO - Running setup.py (path:/tmp/pip-HYyaaW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:16 INFO - Running setup.py (path:/tmp/pip-IXqufZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:16 INFO - Running setup.py (path:/tmp/pip-nt3vMP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:16 INFO - Running setup.py (path:/tmp/pip-IAkigK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:16 INFO - Running setup.py install for manifestparser 18:30:16 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:30:16 INFO - Running setup.py install for mozcrash 18:30:16 INFO - Running setup.py install for mozdebug 18:30:16 INFO - Running setup.py install for mozdevice 18:30:16 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:30:16 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:30:16 INFO - Running setup.py install for mozfile 18:30:16 INFO - Running setup.py install for mozhttpd 18:30:17 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Running setup.py install for mozinfo 18:30:17 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Running setup.py install for mozInstall 18:30:17 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Running setup.py install for mozleak 18:30:17 INFO - Running setup.py install for mozlog 18:30:17 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Running setup.py install for moznetwork 18:30:17 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Running setup.py install for mozprocess 18:30:17 INFO - Running setup.py install for mozprofile 18:30:17 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Running setup.py install for mozrunner 18:30:17 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:30:17 INFO - Running setup.py install for mozscreenshot 18:30:18 INFO - Running setup.py install for moztest 18:30:18 INFO - Running setup.py install for mozversion 18:30:18 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:30:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:18 INFO - Cleaning up... 18:30:18 INFO - Return code: 0 18:30:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:18 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 0x7fa487375c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd1d6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe13d70>, '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 0xe14b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe14fb0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:30:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:18 INFO - Using env: {'DISPLAY': ':0', 18:30:18 INFO - 'HOME': '/home/cltbld', 18:30:18 INFO - 'LANG': 'en_US.UTF-8', 18:30:18 INFO - 'LANGUAGE': 'en_US:en', 18:30:18 INFO - 'LOGNAME': 'cltbld', 18:30:18 INFO - 'MAIL': '/var/mail/cltbld', 18:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:18 INFO - 'MOZ_NO_REMOTE': '1', 18:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:18 INFO - 'NO_EM_RESTART': '1', 18:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:18 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:18 INFO - 'SHELL': '/bin/bash', 18:30:18 INFO - 'SHLVL': '1', 18:30:18 INFO - 'TERM': 'linux', 18:30:18 INFO - 'TMOUT': '86400', 18:30:18 INFO - 'USER': 'cltbld', 18:30:18 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 18:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:18 INFO - '_': '/tools/buildbot/bin/python'} 18:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:18 INFO - Running setup.py (path:/tmp/pip-T3loQ5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 18:30:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:18 INFO - Running setup.py (path:/tmp/pip-TJyLt8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:18 INFO - Running setup.py (path:/tmp/pip-nZ1X7U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:30:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:18 INFO - Running setup.py (path:/tmp/pip-y4sZCC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:18 INFO - Running setup.py (path:/tmp/pip-FsBjnT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 18:30:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:18 INFO - Running setup.py (path:/tmp/pip-oD5qhk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 18:30:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:18 INFO - Running setup.py (path:/tmp/pip-vkXOs_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 18:30:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:18 INFO - Running setup.py (path:/tmp/pip-KpCsSd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:19 INFO - Running setup.py (path:/tmp/pip-RxOajE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:19 INFO - Running setup.py (path:/tmp/pip-KayHlU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:19 INFO - Running setup.py (path:/tmp/pip-OI2INk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:19 INFO - Running setup.py (path:/tmp/pip-ZNPgOC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:19 INFO - Running setup.py (path:/tmp/pip-UR4dmX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:19 INFO - Running setup.py (path:/tmp/pip-iczv34-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:19 INFO - Running setup.py (path:/tmp/pip-DEVSDj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:19 INFO - Running setup.py (path:/tmp/pip-KZ8GaF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 18:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:19 INFO - Running setup.py (path:/tmp/pip-SkB25x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:30: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.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:30: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 mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:30:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:21 INFO - Downloading blessings-1.5.1.tar.gz 18:30:21 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:30:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:21 INFO - Installing collected packages: blessings 18:30:21 INFO - Running setup.py install for blessings 18:30:21 INFO - Successfully installed blessings 18:30:21 INFO - Cleaning up... 18:30:21 INFO - Return code: 0 18:30:21 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:30:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:30:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:30:21 INFO - Reading from file tmpfile_stdout 18:30:21 INFO - Current package versions: 18:30:21 INFO - argparse == 1.2.1 18:30:21 INFO - blessings == 1.5.1 18:30:21 INFO - blobuploader == 1.2.4 18:30:21 INFO - docopt == 0.6.1 18:30:21 INFO - manifestparser == 1.1 18:30:21 INFO - mozInstall == 1.12 18:30:21 INFO - mozcrash == 0.16 18:30:21 INFO - mozdebug == 0.1 18:30:21 INFO - mozdevice == 0.47 18:30:21 INFO - mozfile == 1.2 18:30:21 INFO - mozhttpd == 0.7 18:30:21 INFO - mozinfo == 0.9 18:30:21 INFO - mozleak == 0.1 18:30:21 INFO - mozlog == 3.1 18:30:21 INFO - moznetwork == 0.27 18:30:21 INFO - mozprocess == 0.22 18:30:21 INFO - mozprofile == 0.27 18:30:21 INFO - mozrunner == 6.11 18:30:21 INFO - mozscreenshot == 0.1 18:30:21 INFO - mozsystemmonitor == 0.0 18:30:21 INFO - moztest == 0.7 18:30:21 INFO - mozversion == 1.4 18:30:21 INFO - psutil == 3.1.1 18:30:21 INFO - requests == 1.2.3 18:30:21 INFO - wsgiref == 0.1.2 18:30:21 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:21 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 0x7fa487375c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd1d6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe13d70>, '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 0xe14b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe14fb0>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:30:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 18:30:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:30:21 INFO - Using env: {'DISPLAY': ':0', 18:30:21 INFO - 'HOME': '/home/cltbld', 18:30:21 INFO - 'LANG': 'en_US.UTF-8', 18:30:21 INFO - 'LANGUAGE': 'en_US:en', 18:30:21 INFO - 'LOGNAME': 'cltbld', 18:30:21 INFO - 'MAIL': '/var/mail/cltbld', 18:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:21 INFO - 'MOZ_NO_REMOTE': '1', 18:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:21 INFO - 'NO_EM_RESTART': '1', 18:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:21 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:21 INFO - 'SHELL': '/bin/bash', 18:30:21 INFO - 'SHLVL': '1', 18:30:21 INFO - 'TERM': 'linux', 18:30:21 INFO - 'TMOUT': '86400', 18:30:21 INFO - 'USER': 'cltbld', 18:30:21 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 18:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:21 INFO - '_': '/tools/buildbot/bin/python'} 18:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:30:22 INFO - Cleaning up... 18:30:22 INFO - Return code: 0 18:30:22 INFO - Running post-action listener: _resource_record_post_action 18:30:22 INFO - Running post-action listener: _start_resource_monitoring 18:30:22 INFO - Starting resource monitoring. 18:30:22 INFO - ##### 18:30:22 INFO - ##### Running install step. 18:30:22 INFO - ##### 18:30:22 INFO - Running pre-action listener: _resource_record_pre_action 18:30:22 INFO - Running main action method: install 18:30:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:30:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:30:22 INFO - Reading from file tmpfile_stdout 18:30:22 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:30:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:30:22 INFO - Reading from file tmpfile_stdout 18:30:22 INFO - Output received: 18:30:22 INFO - Usage: mozinstall [options] installer 18:30:22 INFO - Options: 18:30:22 INFO - -h, --help show this help message and exit 18:30:22 INFO - -d DEST, --destination=DEST 18:30:22 INFO - Directory to install application into. [default: 18:30:22 INFO - "/builds/slave/test-pgo"] 18:30:22 INFO - --app=APP Application being installed. [default: firefox] 18:30:22 INFO - mkdir: /builds/slave/test-pgo/build/application 18:30:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:30:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:30:36 INFO - Reading from file tmpfile_stdout 18:30:36 INFO - Output received: 18:30:36 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:30:36 INFO - Running post-action listener: _resource_record_post_action 18:30:36 INFO - ##### 18:30:36 INFO - ##### Running run-tests step. 18:30:36 INFO - ##### 18:30:36 INFO - Running pre-action listener: _resource_record_pre_action 18:30:36 INFO - Running main action method: run_tests 18:30:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:30:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:30:36 INFO - Python 2.7.3 18:30:36 INFO - Return code: 0 18:30:36 INFO - grabbing minidump binary from tooltool 18:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:30:36 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 0xe13d70>, '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 0xe14b20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe14fb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:30:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 18:30:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:30:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:30:36 INFO - Return code: 0 18:30:36 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:30:36 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:30:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:30:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:30:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:30:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:30:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost 18:30:36 INFO - Using env: {'DISPLAY': ':0', 18:30:36 INFO - 'HOME': '/home/cltbld', 18:30:36 INFO - 'LANG': 'en_US.UTF-8', 18:30:36 INFO - 'LANGUAGE': 'en_US:en', 18:30:36 INFO - 'LOGNAME': 'cltbld', 18:30:36 INFO - 'MAIL': '/var/mail/cltbld', 18:30:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:30:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:36 INFO - 'MOZ_NO_REMOTE': '1', 18:30:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:36 INFO - 'NO_EM_RESTART': '1', 18:30:36 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:30:36 INFO - 'PWD': '/builds/slave/test-pgo', 18:30:36 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:30:36 INFO - 'SHELL': '/bin/bash', 18:30:36 INFO - 'SHLVL': '1', 18:30:36 INFO - 'TERM': 'linux', 18:30:36 INFO - 'TMOUT': '86400', 18:30:36 INFO - 'USER': 'cltbld', 18:30:36 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266', 18:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:36 INFO - '_': '/tools/buildbot/bin/python'} 18:30:36 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] with output_timeout 3600 18:30:37 INFO - mozversion INFO | application_buildid: 20151123173004 18:30:37 INFO - mozversion INFO | application_changeset: 65cd2c7696e438c74bfbb0ad40e18f411dbead38 18:30:37 INFO - mozversion INFO | application_display_name: Nightly 18:30:37 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:30:37 INFO - mozversion INFO | application_name: Firefox 18:30:37 INFO - mozversion INFO | application_remotingname: firefox 18:30:37 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 18:30:37 INFO - mozversion INFO | application_vendor: Mozilla 18:30:37 INFO - mozversion INFO | application_version: 45.0a1 18:30:37 INFO - mozversion INFO | platform_buildid: 20151123173004 18:30:37 INFO - mozversion INFO | platform_changeset: 65cd2c7696e438c74bfbb0ad40e18f411dbead38 18:30:37 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 18:30:37 INFO - mozversion INFO | platform_version: 45.0a1 18:30:37 INFO - 2015-11-23 18:30:37,060 DEBUG : using testdate: 1448332237 18:30:37 INFO - 2015-11-23 18:30:37,060 DEBUG : actual date: 1448332237 18:30:37 INFO - 2015-11-23 18:30:37,069 INFO : starting webserver on 'localhost:34444' 18:30:37 INFO - 2015-11-23 18:30:37,070 INFO : Starting test suite talos-linux64-ix-035.e 18:30:37 INFO - 2015-11-23 18:30:37,070 INFO : Starting test dromaeo_css 18:30:37 INFO - 2015-11-23 18:30:37,070 DEBUG : operating with platform_type : linux_ 18:30:37 INFO - 2015-11-23 18:30:37,071 INFO : Initialising browser for dromaeo_css test... 18:30:37 INFO - 2015-11-23 18:30:37,088 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUnU5ft/profile http://localhost:34444/getInfo.html 18:30:42 INFO - 2015-11-23 18:30:42,684 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:30:42 INFO - 2015-11-23 18:30:42,684 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:30:42 INFO - 2015-11-23 18:30:42,684 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:30:42 INFO - 2015-11-23 18:30:42,684 DEBUG : BROWSER_OUTPUT: __metrics 18:30:43 INFO - 2015-11-23 18:30:43,769 INFO : Browser initialized. 18:30:43 INFO - 2015-11-23 18:30:43,770 INFO : Running cycle 1/1 for dromaeo_css test... 18:30:43 INFO - 2015-11-23 18:30:43,770 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUnU5ft/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:30:55 INFO - 2015-11-23 18:30:55,512 DEBUG : BROWSER_OUTPUT: RSS: Main: 140529664 18:30:55 INFO - 2015-11-23 18:30:55,513 DEBUG : BROWSER_OUTPUT: 18:31:45 INFO - 2015-11-23 18:31:45,135 DEBUG : BROWSER_OUTPUT: 1448332305131 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:33:54 INFO - 2015-11-23 18:33:54,112 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34444/tests/dromaeo/cssquery-ext.html) 18:33:55 INFO - 2015-11-23 18:33:55,197 DEBUG : BROWSER_OUTPUT: RSS: Main: 148832256 18:33:55 INFO - 2015-11-23 18:33:55,197 DEBUG : BROWSER_OUTPUT: 18:36:57 INFO - 2015-11-23 18:36:57,123 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/dromaeo/cssquery-ext.html (next: http://localhost:34444/tests/dromaeo/cssquery-jquery.html) 18:36:57 INFO - 2015-11-23 18:36:57,705 DEBUG : BROWSER_OUTPUT: RSS: Main: 142659584 18:36:57 INFO - 2015-11-23 18:36:57,705 DEBUG : BROWSER_OUTPUT: 18:39:59 INFO - 2015-11-23 18:39:59,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34444/tests/dromaeo/cssquery-mootools.html) 18:39:59 INFO - 2015-11-23 18:39:59,950 DEBUG : BROWSER_OUTPUT: RSS: Main: 143200256 18:39:59 INFO - 2015-11-23 18:39:59,950 DEBUG : BROWSER_OUTPUT: 18:43:01 INFO - 2015-11-23 18:43:01,640 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34444/tests/dromaeo/cssquery-prototype.html) 18:43:02 INFO - 2015-11-23 18:43:02,242 DEBUG : BROWSER_OUTPUT: RSS: Main: 141950976 18:43:02 INFO - 2015-11-23 18:43:02,242 DEBUG : BROWSER_OUTPUT: 18:46:04 INFO - 2015-11-23 18:46:04,482 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34444/tests/dromaeo/cssquery-yui.html) 18:46:05 INFO - 2015-11-23 18:46:05,104 DEBUG : BROWSER_OUTPUT: RSS: Main: 142045184 18:46:05 INFO - 2015-11-23 18:46:05,105 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,487 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/dromaeo/cssquery-yui.html (next: http://localhost:34444/tests/dromaeo/cssquery-dojo.html) 18:49:07 INFO - 2015-11-23 18:49:07,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 145068032 18:49:07 INFO - 2015-11-23 18:49:07,733 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,734 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:49:07 INFO - 2015-11-23 18:49:07,735 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:49:07 INFO - 2015-11-23 18:49:07,735 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:49:07 INFO - 2015-11-23 18:49:07,735 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:49:07 INFO - 2015-11-23 18:49:07,736 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3250.138713240991;3292.045306861012;3315.535824984504;3341.33173365327;3352.882649107282;5250.264962905197;5434.918476222855;5483.479069488402;5509.834704958847;5544.8336549903515;5375.6774593524415;5483.287172667548;5483.561315094793;5503.724813759316;5541.556675465963;4637.304404339359;4945.258211268311;5075.238714192882;5081.2124120761155;5089.211172268286;4782.378290822188;4965.851024469272;5053.6715113517685;5063.46833582474;5083.593312534988;4908.901821963558;4967.230079337691;5080.923786143174;5082.872928176826;5105.846824595268;4898.044881248158;4915.827946021908;4922.901541969176;4939.703617782909;4965.329680493123;4881.804727810883;4898.338724272249;4918.2376731606655;4961.107000739866;4992.825251116194;5148.3564554430695;5169.586433085382;5187.0922588546855;5197.922031169535;5228.5555727763185;5821.155932389822;5869.79455719052;5872.442117998783;5877.265341832271;5881.823545293648;5738.770449182028;5840.328362238354;5858.502027327682;5887.234659494281;5915.319738230116;5542.75057622408;5631.718414079321;5639.548836093103;5643.80246691372;5663.235463212455;6050.122732203806;6069.393060693896;6116.847078823065;6120.051392034242;6122;5693.60144789869;5750.942490575125;5787.160861675034;5868.765249390062;5891.7054147292465;5968.522518198533;6005.549583781234;6008.21893153896;6021.57848950578;6022.307434644984;5920.733566989496;5976.671283079386;5984.311804142492;5986.5210783137245;5992.610480318765;5756.222909907193;5812.7674893003805;5826.679532625746;5856.092305692625;5925.762969481172;4495.235809912323;4578.381918440968;4591.44902611682;4605.217113090783;4644.140833945731;4006.939895901564;4077.55146933837;4101.077257617012;4121.258173528794;4140.337545992687;5531.723413829292;5534.08687566547;5539.335279766373;5595.636283641632;5631.155326700946;5935.347111817697;5958.6424814511265;5976.85057873548;5978.850528736816;5980.700964951822;5516.724163791875;5635.408282130399;5721.370649228582;5741.971290143606;5746.942530574641;5083.339165908408;5085.110105731482;5086.491350864958;5103.081445339801;5118.10433174193;6087.8478038048515;6134.4172303631085;6140.232470941132;6142.109394137914;6142.355052719578;5737.053386191232;5776.96014717355;5831.1253312001845;5889.322727886177;5928.851778705567;6138.171346868295;6224.813255602339;6238.438540531374;6245.781397651061;6262.968685156545;5851.268591426072;5888.823335299948;5913.733881975408;5916.3492015877355;5925.733341999534;5665.830025099253;5789.710514474344;5898.469137777621;5975.55183361241;6007.219061522149;5817.476427121579;5826.126081087871;5844.590878638454;5859.44335288147;5862.149988251765;5781.884362312814;5828.6502809831545;5834.241548598655;5839.970800145972;5856.560757943086;5559.444055594408;5587.972060139836;5640.351359593699;5682.886342273048;5733.885322293448;5802.100674395476;5864.765409383577;5869.765209391748;5890.587658864009;5897.764089436546;5435.972820135874;5648.209250704823;5672.4894759471845;5714.942850571441;5757.8560535986935 18:49:07 INFO - 2015-11-23 18:49:07,737 DEBUG : BROWSER_OUTPUT: |1;ext.html;582;596.1623918394652;598.5122125467743;603.3393434189563;603.7041849493748;38287.80856095719;38668.226635467276;38752.80623596878;38863;39103.21793564127;41760;42185;42230.366544501856;42317.57682423175;42333.7883310583;8535.530545819962;8550.230479256865;8697.130286971316;8699.39104262702;8707.085755995624;8486.078074716681;8607.655693772242;8612.181842724962;8621.05168431472;8643.265322447569;8488.702895398663;8626.223639872442;8645.30837532999;8648.65405383784;8662.566871656423;8600.827983440358;8631.870521942175;8679.435836670596;8689.7827554311;8698.434601750865;8448.281896038845;8552.486850788973;8586.055533891267;8599.097094805018;8624.137586241388;39366.40950385747;39523.40714889279;39683.40474892879;39741.40387894155;39993.60006399928;3280.3603297357026;3527.6119626841282;4002.5597184309704;4018.9799051004557;4184.623383895434;29058.41883162325;29694.851525742448;30106.54840177399;30404.69595304041;30789.38421231563;3493.196564790087;3626.274745050974;3631.5823680276844;3666.8899933002053;3741.8690345837763;269476;270847;272958;273456;274260;41752.37371439431;42371.57628423738;58848.41151588515;59143.70428147918;59298;40832.59167408347;40942.59057409447;40971;41117.1776564467;41124.79437602793;11984.940075299568;12116.878831211752;12166;12211.206271592495;12213.328266945226;9710.902890971;9756.46339451337;9774.31579789408;9782;9789.167920726817;14205.92897035529;14259.78610320846;14270.500532481314;14329.928350358181;14358.425662973574;5913.408659134138;5981.132735753378;5991.101334799728;6029.939700603026;6068.696565171724;892.0633334998225;913.5158366065996;975.9024097590184;979.4270351844109;1039.9844002339971;28324.43351133007;28601.284967875552;28674.569881451796;28872.42255154885;33169.170770730445;990.9752256193652;995.785906030207;1021.371856308365;1032.442481060219;1123.646051493777;14700.926495367456;14925.776113358308;15038.924805375904;15176.241187940559;15207.543773686808;380684;388805;389080;389692;390244;52575;52751.73624131932;53162.734186328824;53223.20165197525;53310.733446333295;1244.9688757781128;1363.0935427940508;1364.6861221918816;1369.3632460905528;1380.758367285741;27817.357351051618;28626.856865715537;28645.28386790347;28982.710172898845;29046.854765726035;44691.32963005493;45102.32346514871;45104;45122;45141.54858451374;288629;288953;291979;292698;293415;27965.860170699016;28392.716072839008;31648.367032659677;31820.8408957963;32580.837095814368;40782.59217407788;40835;40898.1820363597;41830.37254441125;41940.790296048326;33485.33029339448;34507.48238776471;34874.47688284729;35235.29529409449;35301.78309417841;35223.29553408969;35526.46710299399;35546.64453355433;35616.287674245854;35740;34231.65768342285;34825.6069757217;35014;35062.47406288959;35129.473057904666;31315.84342078275;31405.214869628442;31719.682803171672;31940.84029579837;32268;24448;24505.877470612533;24690.50618987646;24738.010479580615;24876.253712388658 18:49:07 INFO - 2015-11-23 18:49:07,739 DEBUG : BROWSER_OUTPUT: |2;jquery.html;361.67449295633924;364.0589077235347;365.4463487815959;365.99085022874425;367.9484872117904;16583.751243731338;16935.91532042341;17537.91231043843;17661.55846103848;18304.359347422844;11089.112870970323;11122.888771112286;11136;11790.938815506603;16612.58468538287;4808.759562021903;4945.109880221558;5043.142665746814;5347.1177255752755;5368.033753924292;5310.15037593985;5370.006548788487;5371.704556249405;5371.7582708778;5377.569794416457;5038.168702164138;5088.872778180556;5197.974010129963;5440.319960004999;5440.619156659035;5156;5156.123459011977;5161.587073034186;5180.766865491043;5193.16909294513;4968.503149685039;5089.05852417304;5093.032323858455;5125.846224613268;5139.100657384943;12459.314737689423;12468.002559795284;12501.874981250163;16810.83189168105;19922.501937451536;2096.6121267565395;2119.205298013245;2170.728658917635;2171.6091103601348;2173.500094978148;13322.733545329136;13400.5309814157;13401;13437.328133593379;13629.795553066711;1781.5546113471632;1792.0681245752266;1802.2790883646514;1821.6174603333316;1824.42530602861;61459;61779.14662560086;61957.38042619561;65520.3447965519;66980.33019669789;9891.851622225673;10034.297599167989;10144.44205568686;10153.13698335635;10201.795964080677;12817.551385701456;12893.226406415644;12901.354932253349;12933.482660693468;12954.352282386031;6974.128233970754;7162.570245785269;7193.064901562763;7350.93411455336;7379.077615298088;6897.965510172417;6934.618595977169;6939.132608423947;6978.581285122909;6997.930020699728;4180.268453020709;4271.551487093872;4289.506706728704;4312.116016216668;4343.543927887589;1751.9474415767547;1753.386314789839;1754.526277904984;1755.3943889358152;1792.7759030121235;828.2873588995966;916.7937214126902;924.5562130177552;985.6599473181741;1046.6493724602192;16348.918255408647;16721.581960451087;16912.154392280336;17054.403095891583;17352.91323543374;909.7179874239007;949.7673070097732;953.5661274120258;971.5967873332629;980.2305190425482;9731.951340243395;9871.950640246752;9926.702198934043;9936.254780891462;10056.446895420675;537159;544269;547631;565909;573485;77777;79830;81547;81851;83666;1081.5025088459397;1092.836074588818;1094.6442406218011;1096.681962230944;1103.4386343324268;15058.247087645355;15529.68940621204;15563.37746490173;15650.9217453912;15680.372785088925;31893.68106318907;32040.198995024377;33335.16662083467;33382.83308583441;33696.15759606029;46398.53601464077;46815;49515.75242123767;49897.251541226186;49901;9161.908380916373;9567.569459374485;9617.182539484222;9657.517124143906;9726.75683107928;11328.603498877499;15553.766693499832;16792.412265570645;17360.82639173592;18564.628707425505;13706.177629341873;13911.304434778018;14118.223497707313;14450;14679.192644404233;14039.929800350932;14071;14110.223937683526;14934.626634333794;15200;14811.555653330417;15344.769828452805;15496.612584685474;15567.76648350253;15595.688086238439;9676.080772326617;9736.805263894825;9870.407775533491;9878.654247101316;9909.355891867006;14962.476313328982;15052.924735376253;15211.847881521486;15342.76985845191;15411.845881541041 18:49:07 INFO - 2015-11-23 18:49:07,739 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1487.9107253564785;1550.9844901550985;1624.9512514624566;1687.6287216812304;1785.1252886085813;7660.961695191523;7833.686652533892;8010.91989080109;8014.3187829034605;8036.035675718915;7742.574158421285;7748.263914928087;7807.063152421717;7807.804804879881;7819.061712594483;2201.086549082129;2238.6194346961056;2263.6830843681896;2264.0264686184933;2267.1044937249776;2272.9204477843277;2289.610766169747;2353.211674089182;2360.221127028085;2362.527494501098;2215.3686199433164;2259.0737797503025;2276.8633881967053;2278.943026424336;2280;2251.6960210371717;2258.3563684349965;2283.1209984156135;2302.435903203709;2307.5846347657416;2310.202979971907;2312.352541288442;2336.5210131923086;2342.145117032288;2344.4724936889234;7576.621168941531;7733.574653394061;7754;7780.105287891908;7805.882911756328;4691.6481263905;4782.450018247877;4791.640626952958;4796.496367881357;4835.879103022417;2876.5972763813083;2876.913692589226;2944.4258369617933;2947.204254851178;2984.820910745362;911.9224865886407;926.4904302633524;930.9255259579218;949.3164921256684;951.8001219743862;10591.205659575562;10616.681499555025;10619.522121504473;10633.361998280128;10652.733681657943;4177.039280965395;4233.428487154257;4266.104118135165;4279.786010699452;4279.828806847753;8823.735287941372;8825.735227943173;8829.735107946772;8834.028256891737;8846.646134154562;4733.621310295168;4734.928976065362;4750.501197374226;4759.214729569651;4766.165920963817;4307.719998200107;4337.934930976038;4350.8042138103865;4364.5635436456105;4370.38814565961;3535.2045789697113;3551.8224088795973;3556.4309710446205;3557.5197348358156;3563.928721425557;1085.766560189563;1104.2932523185168;1116.8771435142128;1121.1143196874377;1121.8934201250868;960.3757557587485;966.0966995858895;1022.0290723812408;1127.8477405550145;1346.4344975110478;7096.929030709627;7141.107361579802;7142.964285178541;7155.034070400533;7186.568805871664;593.5044238061181;599.1970759182716;599.5743022454106;602.524006035236;751.8872169174558;5654.529444248191;5658.519025882846;5718.456746609066;5768.394318596487;5788.21859049023;9158.633654653873;9486.193673537826;9490.952545237367;9550.331476796557;9609.423434593948;9179.81640367189;9231.584578693975;9335.346525743133;9376.953115234517;9487.430754154773;7410.740624078131;7411.88882166768;7496.587687677121;7497.287757663123;7508.699652013967;7260.818479538054;7280.271972802678;7381.11426628808;7411.147718012282;7416.406687465098;8256.091829898587;8267.917320826715;8359.456635318686;8515.74452766418;8534.658613655385;8393.32853371741;8580.14198580137;8619.655213791379;8642.611082501176;8658.523781192094;7042.401395881408;7077.964610176916;7154.35610795031;7157.355837974607;7170.103737032871;7584.582847943416;7695.268949449794;7730.613469326398;7740.380769538536;7770.961145194238;6887.414569761627;7081.681324340314;7159.677814498465;7261.128664560287;7340.192578816219;6852.534103355452;6924.272951340238;6931.618760968196;6977.511574189758;7000.229974702676;6887.758928437481;6936.34104760047;6936.965315173392;6953.33943275388;6953.617551034742;6383.93616063852;6417.71120299597;6448.290688024407;6486.416222539994;6498.285188629153;6300.991841305369;6301.275353334425;6440.613563186036;6452.677366131581;6453.774117905851 18:49:07 INFO - 2015-11-23 18:49:07,740 DEBUG : BROWSER_OUTPUT: |4;prototype.html;321.7651114686279;329.49916127486216;336.9157710572357;342.3290350912212;348.2338854520055;6178.96910515448;6297.716602752876;6354.173957385545;6397.00846368813;6399.744010239585;6046.12331211974;6256.09286653435;6272.059191121322;6276.278228003781;6285.245770507546;4805;4805.663603547753;4821.662483626147;4822.5900798432;4829.348038014876;4830.855074347775;4834.371531700874;4840.104580652591;4845.709257444564;4850.514948505156;4684.13343531446;4776.283557466373;4788.688735232199;4797.0405918816205;4797.328374027639;4767.475577686451;4773.615651461089;4780.474147843769;4782.97608511957;4794.664373493857;4180.24755544002;4188.68584856137;4194.3918131870705;4204.894877628053;4214.2414365414215;5889.381614930457;5904.586678932476;5946.762129514815;5953.642781433128;5963.612365196248;5374.355077390699;5559.749811258503;5568.443155684399;5578.609497335188;5590.720463976825;6580.901286480706;6608.438282745972;6610.438112760421;6630.900536491957;6636.93363066368;4599.5630415110845;4623.2371658676275;4634.930476042862;4635.049814787961;4644.419447569054;6737.528373013891;6814.761483348059;6907.792766217021;6916.170059592881;6920.2387737348345;4759.4764575896625;4760.500147484464;4761.738104404221;4765.0946320199055;4765.737884416321;4816.975915120472;4819.445763737215;4820.807167713322;4822.083804077214;4839.862632281411;5554.611177217636;5568.248286481273;5569.10894256917;5571.275734154615;5574;6540.607563546202;6622.966885165543;6638.867222655616;6639.535232533676;6726.630035348103;1618.8300228476076;1650.3976048742363;1655.6357601327688;1659.0460485221047;1659.0957927929308;1081.0648788797632;1081.3079629037422;1091.37930173692;1109.1681239070697;1109.4840898982013;6349.492040636738;6351.396617321347;6377.808665740035;6413.615183089029;6435.292117867031;6700.463962882957;6731.259561448237;6787.796366108926;6789.456843452512;6810.897836532456;6285.685715714196;6349.428551430394;6391.169148010729;6393.808185754435;6403.615783053032;3697.7411581189594;3702.1114932416026;3721.6092310307567;3721.7208709346905;3735.103575142001;7054.400375967997;7069.293070692993;7075.610841403669;7078.186008608973;7082.362587367162;6922.238553759083;6952.5480843745;6981.441484681213;6984.720611175598;7003.894941575881;7238.239984801626;7246.420286377077;7246.746363877239;7249.420046396275;7260.056192694916;6924.61914594682;6961.825954351182;6963.059986901703;6976.720931162697;6995.965020174866;6754.223264324469;6863.725450982104;6887.242403335729;6906.723731050702;6914.101166848278;7227.096612923385;7236.131664200329;7239.239879812754;7251.782446526613;7252.709891604488;4636.188666983197;4670.602998745009;4677.2750223715375;4679.3916790816975;4682.993156471375;6802.319768023158;6942.757003505055;6945.548539344926;6953.617551034539;6957.9304206959305;6713.697883595348;6740.662966851736;6743.12339395895;6752.392284694204;6761.86476270462;6701.430378417889;6730.596164230162;6730.899036514358;6742.898856517058;6756.358145976124;6480.837979050561;6599.637019963948;6613.503987200883;6613.900791488035;6615.669216539058;6705.865882682221;6760.121184246016;6764.763233286812;6773.085633439423;6787.21946976104;6523.836904077436;6540.411362977355;6544.738210471718;6547.508936829661;6547.639879806466 18:49:07 INFO - 2015-11-23 18:49:07,741 DEBUG : BROWSER_OUTPUT: |5;yui.html;746.985060298794;819.1948399683472;837.782176634075;854.5342788180441;859.965601375945;14150.221737804417;14310.57068287953;14497.275136243199;14520.128792272471;14572.854271457281;15170.089794612331;15311.6937661247;15330.616734581614;15355.846441535581;15373.308201130949;823.6746485138367;826.6528058215533;834.995825020874;835.3567752830317;836.1638361638362;839.7480755773274;847.9788005299855;851.6678495386805;852.4970267542149;853.8591132463137;879.4942907827992;882.4131952251745;889.9955500222522;890.5636238243279;893.2005854759985;879.3580686099183;880.1286726141107;884.3809333466567;885.2033170146855;885.8936927568701;646.9644169570672;650.2164891305965;650.4178760009764;650.6746626686656;650.8633187030729;14395.640108997359;14410.207438590873;14537.418503259856;14543.709125817424;14548.127112373291;12642.936785316106;12798.424070916832;12869.22784632925;12872.806907896389;13363.465461381533;10089.697309080739;10539.525721342558;10610.363378197333;10654.893451065545;10868.67393978182;3699.408094704834;3756.1173124315765;3788.034051316924;3871.457995880579;3885.8834234972715;50460.74769626165;50541.74729126331;50655.24017139746;50674.49325506735;50735.49264507345;10098.596056157672;10472.476376181161;10496.737581560523;10583;10889;19195.712064319046;19232.23071077169;19726.506837328896;19737.111829967685;19751.20995160206;7466.178720340758;7512.962435187789;7686.923130768732;7969.681212751425;8025.438219324591;4173.728707633994;4255.1915136124035;4275.46556680415;4291.377750226262;4317.740935543878;3101.8138911665374;3102.8758849646215;3104.5343198520404;3124.7812653114065;3127.5934128563595;539.5090467674476;555.303094616254;557.4648337595953;559.7117484495488;567.4750855458684;544.3060098374525;593.8752861898963;622.5579838314757;628.8805127025851;628.9371062893765;7983.880241796378;8420.53687047206;8448.873266901;8471.915280847114;8734.0829212933;553.941836107211;602.8251806975928;620.454000479575;663.2571519897746;679.9354061364262;3794.29805485986;3993.6206060424215;4033.878983630496;4046.3121269383687;4067.5322337930925;59554;59571.404285957455;60084;60311.396886030576;60441.69779151076;49953.25070123951;50159.24761128586;50411.74794126006;50895;50953.74523127435;46269.305960410624;46782.76608616867;47256.7637161812;47276.05447891023;48111.27833082437;7993.920060799318;8036.83926321482;8093.797655058671;8120.675172993014;8285.627146778288;36787.816060919526;37775.81112094422;38067.61932380641;38684.6131538681;38919.22161556697;46840;47340.52659473361;47411;47416.52583474121;47939.76030119827;10964.122870170208;11340.20618556693;11447.198696091195;11447.656570302905;11816.76366472683;11876.76246475083;12039.217450865666;12075.758484830078;12540.933177208522;12817.615471535868;11829.467673954521;12274.324912129561;12445.191062580903;12455.377231138587;12564.623061308175;12042.698932526757;12172.57395991136;12310.07674424404;12512.124151309321;12697.87302127004;10626.787464250827;10717.517711702836;11924.642260732191;11962.222455540363;12564;9293.628254869729;10048.296619236584;10049;10281.897181028095;10458.581656733646;8630.611622477129;8881.156290152425;9206.907930920874;9365.344425890395;10278.486075696335 18:49:07 INFO - 2015-11-23 18:49:07,741 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:49:07 INFO - 2015-11-23 18:49:07,741 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:49:07 INFO - 2015-11-23 18:49:07,742 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,310 18:49:07 INFO - 2015-11-23 18:49:07,742 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:49:07 INFO - 2015-11-23 18:49:07,742 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333347733__endTimestamp 18:49:07 INFO - 2015-11-23 18:49:07,742 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,742 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:49:07 INFO - 2015-11-23 18:49:07,742 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:49:07 INFO - 2015-11-23 18:49:07,742 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,743 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5486.40 Median:5721.37 stddev:622.73 (10.9%) stddev-sans-first:599.42 18:49:07 INFO - 2015-11-23 18:49:07,743 DEBUG : BROWSER_OUTPUT: Values: 3250.1 3292.0 3315.5 3341.3 3352.9 5250.3 5434.9 5483.5 5509.8 5544.8 5375.7 5483.3 5483.6 5503.7 5541.6 4637.3 4945.3 5075.2 5081.2 5089.2 4782.4 4965.9 5053.7 5063.5 5083.6 4908.9 4967.2 5080.9 5082.9 5105.8 4898.0 4915.8 4922.9 4939.7 4965.3 4881.8 4898.3 4918.2 4961.1 4992.8 5148.4 5169.6 5187.1 5197.9 5228.6 5821.2 5869.8 5872.4 5877.3 5881.8 5738.8 5840.3 5858.5 5887.2 5915.3 5542.8 5631.7 5639.5 5643.8 5663.2 6050.1 6069.4 6116.8 6120.1 6122.0 5693.6 5750.9 5787.2 5868.8 5891.7 5968.5 6005.5 6008.2 6021.6 6022.3 5920.7 5976.7 5984.3 5986.5 5992.6 5756.2 5812.8 5826.7 5856.1 5925.8 4495.2 4578.4 4591.4 4605.2 4644.1 4006.9 4077.6 4101.1 4121.3 4140.3 5531.7 5534.1 5539.3 5595.6 5631.2 5935.3 5958.6 5976.9 5978.9 5980.7 5516.7 5635.4 5721.4 5742.0 5746.9 5083.3 5085.1 5086.5 5103.1 5118.1 6087.8 6134.4 6140.2 6142.1 6142.4 5737.1 5777.0 5831.1 5889.3 5928.9 6138.2 6224.8 6238.4 6245.8 6263.0 5851.3 5888.8 5913.7 5916.3 5925.7 5665.8 5789.7 5898.5 5975.6 6007.2 5817.5 5826.1 5844.6 5859.4 5862.1 5781.9 5828.7 5834.2 5840.0 5856.6 5559.4 5588.0 5640.4 5682.9 5733.9 5802.1 5864.8 5869.8 5890.6 5897.8 5436.0 5648.2 5672.5 5714.9 5757.9 18:49:07 INFO - 2015-11-23 18:49:07,743 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,743 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47108.12 Median:213834.00 stddev:84414.42 (39.5%) stddev-sans-first:84578.94 18:49:07 INFO - 2015-11-23 18:49:07,743 DEBUG : BROWSER_OUTPUT: Values: 582.0 596.2 598.5 603.3 603.7 38287.8 38668.2 38752.8 38863.0 39103.2 41760.0 42185.0 42230.4 42317.6 42333.8 8535.5 8550.2 8697.1 8699.4 8707.1 8486.1 8607.7 8612.2 8621.1 8643.3 8488.7 8626.2 8645.3 8648.7 8662.6 8600.8 8631.9 8679.4 8689.8 8698.4 8448.3 8552.5 8586.1 8599.1 8624.1 39366.4 39523.4 39683.4 39741.4 39993.6 3280.4 3527.6 4002.6 4019.0 4184.6 29058.4 29694.9 30106.5 30404.7 30789.4 3493.2 3626.3 3631.6 3666.9 3741.9 269476.0 270847.0 272958.0 273456.0 274260.0 41752.4 42371.6 58848.4 59143.7 59298.0 40832.6 40942.6 40971.0 41117.2 41124.8 11984.9 12116.9 12166.0 12211.2 12213.3 9710.9 9756.5 9774.3 9782.0 9789.2 14205.9 14259.8 14270.5 14329.9 14358.4 5913.4 5981.1 5991.1 6029.9 6068.7 892.1 913.5 975.9 979.4 1040.0 28324.4 28601.3 28674.6 28872.4 33169.2 991.0 995.8 1021.4 1032.4 1123.6 14700.9 14925.8 15038.9 15176.2 15207.5 380684.0 388805.0 389080.0 389692.0 390244.0 52575.0 52751.7 53162.7 53223.2 53310.7 1245.0 1363.1 1364.7 1369.4 1380.8 27817.4 28626.9 28645.3 28982.7 29046.9 44691.3 45102.3 45104.0 45122.0 45141.5 288629.0 288953.0 291979.0 292698.0 293415.0 27965.9 28392.7 31648.4 31820.8 32580.8 40782.6 40835.0 40898.2 41830.4 41940.8 33485.3 34507.5 34874.5 35235.3 35301.8 35223.3 35526.5 35546.6 35616.3 35740.0 34231.7 34825.6 35014.0 35062.5 35129.5 31315.8 31405.2 31719.7 31940.8 32268.0 24448.0 24505.9 24690.5 24738.0 24876.3 18:49:07 INFO - 2015-11-23 18:49:07,744 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,744 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29188.21 Median:17028.92 stddev:90541.22 (531.7%) stddev-sans-first:90769.34 18:49:07 INFO - 2015-11-23 18:49:07,745 DEBUG : BROWSER_OUTPUT: Values: 361.7 364.1 365.4 366.0 367.9 16583.8 16935.9 17537.9 17661.6 18304.4 11089.1 11122.9 11136.0 11790.9 16612.6 4808.8 4945.1 5043.1 5347.1 5368.0 5310.2 5370.0 5371.7 5371.8 5377.6 5038.2 5088.9 5198.0 5440.3 5440.6 5156.0 5156.1 5161.6 5180.8 5193.2 4968.5 5089.1 5093.0 5125.8 5139.1 12459.3 12468.0 12501.9 16810.8 19922.5 2096.6 2119.2 2170.7 2171.6 2173.5 13322.7 13400.5 13401.0 13437.3 13629.8 1781.6 1792.1 1802.3 1821.6 1824.4 61459.0 61779.1 61957.4 65520.3 66980.3 9891.9 10034.3 10144.4 10153.1 10201.8 12817.6 12893.2 12901.4 12933.5 12954.4 6974.1 7162.6 7193.1 7350.9 7379.1 6898.0 6934.6 6939.1 6978.6 6997.9 4180.3 4271.6 4289.5 4312.1 4343.5 1751.9 1753.4 1754.5 1755.4 1792.8 828.3 916.8 924.6 985.7 1046.6 16348.9 16721.6 16912.2 17054.4 17352.9 909.7 949.8 953.6 971.6 980.2 9732.0 9872.0 9926.7 9936.3 10056.4 537159.0 544269.0 547631.0 565909.0 573485.0 77777.0 79830.0 81547.0 81851.0 83666.0 1081.5 1092.8 1094.6 1096.7 1103.4 15058.2 15529.7 15563.4 15650.9 15680.4 31893.7 32040.2 33335.2 33382.8 33696.2 46398.5 46815.0 49515.8 49897.3 49901.0 9161.9 9567.6 9617.2 9657.5 9726.8 11328.6 15553.8 16792.4 17360.8 18564.6 13706.2 13911.3 14118.2 14450.0 14679.2 14039.9 14071.0 14110.2 14934.6 15200.0 14811.6 15344.8 15496.6 15567.8 15595.7 9676.1 9736.8 9870.4 9878.7 9909.4 14962.5 15052.9 15211.8 15342.8 15411.8 18:49:07 INFO - 2015-11-23 18:49:07,745 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,746 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5456.00 Median:6492.35 stddev:2855.41 (44.0%) stddev-sans-first:2847.84 18:49:07 INFO - 2015-11-23 18:49:07,746 DEBUG : BROWSER_OUTPUT: Values: 1487.9 1551.0 1625.0 1687.6 1785.1 7661.0 7833.7 8010.9 8014.3 8036.0 7742.6 7748.3 7807.1 7807.8 7819.1 2201.1 2238.6 2263.7 2264.0 2267.1 2272.9 2289.6 2353.2 2360.2 2362.5 2215.4 2259.1 2276.9 2278.9 2280.0 2251.7 2258.4 2283.1 2302.4 2307.6 2310.2 2312.4 2336.5 2342.1 2344.5 7576.6 7733.6 7754.0 7780.1 7805.9 4691.6 4782.5 4791.6 4796.5 4835.9 2876.6 2876.9 2944.4 2947.2 2984.8 911.9 926.5 930.9 949.3 951.8 10591.2 10616.7 10619.5 10633.4 10652.7 4177.0 4233.4 4266.1 4279.8 4279.8 8823.7 8825.7 8829.7 8834.0 8846.6 4733.6 4734.9 4750.5 4759.2 4766.2 4307.7 4337.9 4350.8 4364.6 4370.4 3535.2 3551.8 3556.4 3557.5 3563.9 1085.8 1104.3 1116.9 1121.1 1121.9 960.4 966.1 1022.0 1127.8 1346.4 7096.9 7141.1 7143.0 7155.0 7186.6 593.5 599.2 599.6 602.5 751.9 5654.5 5658.5 5718.5 5768.4 5788.2 9158.6 9486.2 9491.0 9550.3 9609.4 9179.8 9231.6 9335.3 9377.0 9487.4 7410.7 7411.9 7496.6 7497.3 7508.7 7260.8 7280.3 7381.1 7411.1 7416.4 8256.1 8267.9 8359.5 8515.7 8534.7 8393.3 8580.1 8619.7 8642.6 8658.5 7042.4 7078.0 7154.4 7157.4 7170.1 7584.6 7695.3 7730.6 7740.4 7771.0 6887.4 7081.7 7159.7 7261.1 7340.2 6852.5 6924.3 6931.6 6977.5 7000.2 6887.8 6936.3 6937.0 6953.3 6953.6 6383.9 6417.7 6448.3 6486.4 6498.3 6301.0 6301.3 6440.6 6452.7 6453.8 18:49:07 INFO - 2015-11-23 18:49:07,746 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,746 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5581.27 Median:6352.79 stddev:1692.66 (26.6%) stddev-sans-first:1650.73 18:49:07 INFO - 2015-11-23 18:49:07,746 DEBUG : BROWSER_OUTPUT: Values: 321.8 329.5 336.9 342.3 348.2 6179.0 6297.7 6354.2 6397.0 6399.7 6046.1 6256.1 6272.1 6276.3 6285.2 4805.0 4805.7 4821.7 4822.6 4829.3 4830.9 4834.4 4840.1 4845.7 4850.5 4684.1 4776.3 4788.7 4797.0 4797.3 4767.5 4773.6 4780.5 4783.0 4794.7 4180.2 4188.7 4194.4 4204.9 4214.2 5889.4 5904.6 5946.8 5953.6 5963.6 5374.4 5559.7 5568.4 5578.6 5590.7 6580.9 6608.4 6610.4 6630.9 6636.9 4599.6 4623.2 4634.9 4635.0 4644.4 6737.5 6814.8 6907.8 6916.2 6920.2 4759.5 4760.5 4761.7 4765.1 4765.7 4817.0 4819.4 4820.8 4822.1 4839.9 5554.6 5568.2 5569.1 5571.3 5574.0 6540.6 6623.0 6638.9 6639.5 6726.6 1618.8 1650.4 1655.6 1659.0 1659.1 1081.1 1081.3 1091.4 1109.2 1109.5 6349.5 6351.4 6377.8 6413.6 6435.3 6700.5 6731.3 6787.8 6789.5 6810.9 6285.7 6349.4 6391.2 6393.8 6403.6 3697.7 3702.1 3721.6 3721.7 3735.1 7054.4 7069.3 7075.6 7078.2 7082.4 6922.2 6952.5 6981.4 6984.7 7003.9 7238.2 7246.4 7246.7 7249.4 7260.1 6924.6 6961.8 6963.1 6976.7 6996.0 6754.2 6863.7 6887.2 6906.7 6914.1 7227.1 7236.1 7239.2 7251.8 7252.7 4636.2 4670.6 4677.3 4679.4 4683.0 6802.3 6942.8 6945.5 6953.6 6957.9 6713.7 6740.7 6743.1 6752.4 6761.9 6701.4 6730.6 6730.9 6742.9 6756.4 6480.8 6599.6 6613.5 6613.9 6615.7 6705.9 6760.1 6764.8 6773.1 6787.2 6523.8 6540.4 6544.7 6547.5 6547.6 18:49:07 INFO - 2015-11-23 18:49:07,746 DEBUG : BROWSER_OUTPUT: 18:49:07 INFO - 2015-11-23 18:49:07,746 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14352.02 Median:46526.04 stddev:16543.39 (35.6%) stddev-sans-first:16558.25 18:49:07 INFO - 2015-11-23 18:49:07,748 DEBUG : BROWSER_OUTPUT: Values: 747.0 819.2 837.8 854.5 860.0 14150.2 14310.6 14497.3 14520.1 14572.9 15170.1 15311.7 15330.6 15355.8 15373.3 823.7 826.7 835.0 835.4 836.2 839.7 848.0 851.7 852.5 853.9 879.5 882.4 890.0 890.6 893.2 879.4 880.1 884.4 885.2 885.9 647.0 650.2 650.4 650.7 650.9 14395.6 14410.2 14537.4 14543.7 14548.1 12642.9 12798.4 12869.2 12872.8 13363.5 10089.7 10539.5 10610.4 10654.9 10868.7 3699.4 3756.1 3788.0 3871.5 3885.9 50460.7 50541.7 50655.2 50674.5 50735.5 10098.6 10472.5 10496.7 10583.0 10889.0 19195.7 19232.2 19726.5 19737.1 19751.2 7466.2 7513.0 7686.9 7969.7 8025.4 4173.7 4255.2 4275.5 4291.4 4317.7 3101.8 3102.9 3104.5 3124.8 3127.6 539.5 555.3 557.5 559.7 567.5 544.3 593.9 622.6 628.9 628.9 7983.9 8420.5 8448.9 8471.9 8734.1 553.9 602.8 620.5 663.3 679.9 3794.3 3993.6 4033.9 4046.3 4067.5 59554.0 59571.4 60084.0 60311.4 60441.7 49953.3 50159.2 50411.7 50895.0 50953.7 46269.3 46782.8 47256.8 47276.1 48111.3 7993.9 8036.8 8093.8 8120.7 8285.6 36787.8 37775.8 38067.6 38684.6 38919.2 46840.0 47340.5 47411.0 47416.5 47939.8 10964.1 11340.2 11447.2 11447.7 11816.8 11876.8 12039.2 12075.8 12540.9 12817.6 11829.5 12274.3 12445.2 12455.4 12564.6 12042.7 12172.6 12310.1 12512.1 12697.9 10626.8 10717.5 11924.6 11962.2 12564.0 9293.6 10048.3 10049.0 10281.9 10458.6 8630.6 8881.2 9206.9 9365.3 10278.5 18:49:07 INFO - 2015-11-23 18:49:07,748 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:49:07 INFO - 2015-11-23 18:49:07,748 DEBUG : BROWSER_OUTPUT: 18:49:08 INFO - 2015-11-23 18:49:08,429 INFO : Browser exited with error code: 0 18:49:08 INFO - 2015-11-23 18:49:08,451 INFO : Completed test dromaeo_css (00:18:31) 18:49:08 INFO - 2015-11-23 18:49:08,451 INFO : Starting test kraken 18:49:08 INFO - 2015-11-23 18:49:08,451 DEBUG : operating with platform_type : linux_ 18:49:08 INFO - 2015-11-23 18:49:08,451 INFO : Initialising browser for kraken test... 18:49:08 INFO - 2015-11-23 18:49:08,457 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaegGTo/profile http://localhost:34444/getInfo.html 18:49:12 INFO - 2015-11-23 18:49:12,917 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:49:12 INFO - 2015-11-23 18:49:12,917 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:49:12 INFO - 2015-11-23 18:49:12,918 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:49:12 INFO - 2015-11-23 18:49:12,918 DEBUG : BROWSER_OUTPUT: __metrics 18:49:13 INFO - 2015-11-23 18:49:13,901 INFO : Browser initialized. 18:49:13 INFO - 2015-11-23 18:49:13,902 INFO : Running cycle 1/1 for kraken test... 18:49:13 INFO - 2015-11-23 18:49:13,902 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaegGTo/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:49:25 INFO - 2015-11-23 18:49:25,717 DEBUG : BROWSER_OUTPUT: RSS: Main: 142139392 18:49:25 INFO - 2015-11-23 18:49:25,717 DEBUG : BROWSER_OUTPUT: 18:49:28 INFO - 2015-11-23 18:49:28,185 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34444/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:28 INFO - 2015-11-23 18:49:28,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 142032896 18:49:28 INFO - 2015-11-23 18:49:28,807 DEBUG : BROWSER_OUTPUT: 18:49:31 INFO - 2015-11-23 18:49:31,466 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34444/tests/kraken/driver.html?testName=audio-dft) 18:49:32 INFO - 2015-11-23 18:49:32,052 DEBUG : BROWSER_OUTPUT: RSS: Main: 142884864 18:49:32 INFO - 2015-11-23 18:49:32,052 DEBUG : BROWSER_OUTPUT: 18:49:34 INFO - 2015-11-23 18:49:34,891 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34444/tests/kraken/driver.html?testName=audio-fft) 18:49:35 INFO - 2015-11-23 18:49:35,473 DEBUG : BROWSER_OUTPUT: RSS: Main: 137986048 18:49:35 INFO - 2015-11-23 18:49:35,473 DEBUG : BROWSER_OUTPUT: 18:49:37 INFO - 2015-11-23 18:49:37,485 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34444/tests/kraken/driver.html?testName=audio-oscillator) 18:49:38 INFO - 2015-11-23 18:49:38,108 DEBUG : BROWSER_OUTPUT: RSS: Main: 137248768 18:49:38 INFO - 2015-11-23 18:49:38,108 DEBUG : BROWSER_OUTPUT: 18:49:40 INFO - 2015-11-23 18:49:40,307 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34444/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:49:40 INFO - 2015-11-23 18:49:40,894 DEBUG : BROWSER_OUTPUT: RSS: Main: 137613312 18:49:40 INFO - 2015-11-23 18:49:40,895 DEBUG : BROWSER_OUTPUT: 18:49:43 INFO - 2015-11-23 18:49:43,884 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34444/tests/kraken/driver.html?testName=imaging-darkroom) 18:49:44 INFO - 2015-11-23 18:49:44,470 DEBUG : BROWSER_OUTPUT: RSS: Main: 140165120 18:49:44 INFO - 2015-11-23 18:49:44,470 DEBUG : BROWSER_OUTPUT: 18:49:47 INFO - 2015-11-23 18:49:47,920 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34444/tests/kraken/driver.html?testName=imaging-desaturate) 18:49:48 INFO - 2015-11-23 18:49:48,506 DEBUG : BROWSER_OUTPUT: RSS: Main: 140308480 18:49:48 INFO - 2015-11-23 18:49:48,506 DEBUG : BROWSER_OUTPUT: 18:49:51 INFO - 2015-11-23 18:49:51,324 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34444/tests/kraken/driver.html?testName=json-parse-financial) 18:49:51 INFO - 2015-11-23 18:49:51,912 DEBUG : BROWSER_OUTPUT: RSS: Main: 140406784 18:49:51 INFO - 2015-11-23 18:49:51,912 DEBUG : BROWSER_OUTPUT: 18:49:53 INFO - 2015-11-23 18:49:53,493 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34444/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:49:54 INFO - 2015-11-23 18:49:54,074 DEBUG : BROWSER_OUTPUT: RSS: Main: 139390976 18:49:54 INFO - 2015-11-23 18:49:54,075 DEBUG : BROWSER_OUTPUT: 18:49:55 INFO - 2015-11-23 18:49:55,898 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34444/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:49:56 INFO - 2015-11-23 18:49:56,508 DEBUG : BROWSER_OUTPUT: RSS: Main: 139444224 18:49:56 INFO - 2015-11-23 18:49:56,508 DEBUG : BROWSER_OUTPUT: 18:49:58 INFO - 2015-11-23 18:49:58,588 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34444/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:49:59 INFO - 2015-11-23 18:49:59,162 DEBUG : BROWSER_OUTPUT: RSS: Main: 140984320 18:49:59 INFO - 2015-11-23 18:49:59,162 DEBUG : BROWSER_OUTPUT: 18:50:01 INFO - 2015-11-23 18:50:01,617 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:02 INFO - 2015-11-23 18:50:02,223 DEBUG : BROWSER_OUTPUT: RSS: Main: 139874304 18:50:02 INFO - 2015-11-23 18:50:02,224 DEBUG : BROWSER_OUTPUT: 18:50:04 INFO - 2015-11-23 18:50:04,798 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:05 INFO - 2015-11-23 18:50:05,392 DEBUG : BROWSER_OUTPUT: RSS: Main: 140804096 18:50:05 INFO - 2015-11-23 18:50:05,392 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,081 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34444/tests/kraken/driver.html?testName=ai-astar) 18:50:07 INFO - 2015-11-23 18:50:07,327 DEBUG : BROWSER_OUTPUT: RSS: Main: 140017664 18:50:07 INFO - 2015-11-23 18:50:07,328 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,329 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:50:07 INFO - 2015-11-23 18:50:07,329 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:50:07 INFO - 2015-11-23 18:50:07,329 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:50:07 INFO - 2015-11-23 18:50:07,329 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:50:07 INFO - 2015-11-23 18:50:07,329 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;103;110;111;111;122;110;110;111;110 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;162;125;157;164;128;149;153;133;164;125 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;163;162;161;160;161;162;164;170;163 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |3;audio-fft;78;71;71;72;71;71;151;76;72;83 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;138;92;93;93;133;94;93;143 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;110;110;110;110;110;110;110 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;147;148;147;146;148;148;146;146 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;96;96;96;96;97;96 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;61;62;61;60;62;60;61;62;61 18:50:07 INFO - 2015-11-23 18:50:07,330 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;70;70;69;70;70;69;69;69;69;69 18:50:07 INFO - 2015-11-23 18:50:07,331 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;86;85;84;85;85;84;85;84;84 18:50:07 INFO - 2015-11-23 18:50:07,331 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;120;120;119;126;121;120;123;134;122 18:50:07 INFO - 2015-11-23 18:50:07,331 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;148;150;151;151;149;148;149;148;155;148 18:50:07 INFO - 2015-11-23 18:50:07,331 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;74;66;66;65;69;68;67;65;67;70 18:50:07 INFO - 2015-11-23 18:50:07,331 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:50:07 INFO - 2015-11-23 18:50:07,331 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:50:07 INFO - 2015-11-23 18:50:07,331 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,744 18:50:07 INFO - 2015-11-23 18:50:07,331 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:50:07 INFO - 2015-11-23 18:50:07,332 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333407328__endTimestamp 18:50:07 INFO - 2015-11-23 18:50:07,332 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,333 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:50:07 INFO - 2015-11-23 18:50:07,333 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:50:07 INFO - 2015-11-23 18:50:07,333 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,333 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:4.58 (4.1%) stddev-sans-first:4.86 18:50:07 INFO - 2015-11-23 18:50:07,333 DEBUG : BROWSER_OUTPUT: Values: 111.0 103.0 110.0 111.0 111.0 122.0 110.0 110.0 111.0 110.0 18:50:07 INFO - 2015-11-23 18:50:07,334 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,334 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.00 Median:155.00 stddev:16.53 (10.7%) stddev-sans-first:16.48 18:50:07 INFO - 2015-11-23 18:50:07,334 DEBUG : BROWSER_OUTPUT: Values: 162.0 125.0 157.0 164.0 128.0 149.0 153.0 133.0 164.0 125.0 18:50:07 INFO - 2015-11-23 18:50:07,334 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,334 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:2.77 (1.7%) stddev-sans-first:2.93 18:50:07 INFO - 2015-11-23 18:50:07,335 DEBUG : BROWSER_OUTPUT: Values: 163.0 163.0 162.0 161.0 160.0 161.0 162.0 164.0 170.0 163.0 18:50:07 INFO - 2015-11-23 18:50:07,335 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,335 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.60 Median:72.00 stddev:24.71 (34.3%) stddev-sans-first:26.18 18:50:07 INFO - 2015-11-23 18:50:07,335 DEBUG : BROWSER_OUTPUT: Values: 78.0 71.0 71.0 72.0 71.0 71.0 151.0 76.0 72.0 83.0 18:50:07 INFO - 2015-11-23 18:50:07,336 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,336 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.60 Median:93.00 stddev:21.80 (23.4%) stddev-sans-first:22.64 18:50:07 INFO - 2015-11-23 18:50:07,336 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 138.0 92.0 93.0 93.0 133.0 94.0 93.0 143.0 18:50:07 INFO - 2015-11-23 18:50:07,336 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,336 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 18:50:07 INFO - 2015-11-23 18:50:07,337 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 18:50:07 INFO - 2015-11-23 18:50:07,337 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,337 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 18:50:07 INFO - 2015-11-23 18:50:07,337 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 147.0 148.0 147.0 146.0 148.0 148.0 146.0 146.0 18:50:07 INFO - 2015-11-23 18:50:07,337 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,337 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:50:07 INFO - 2015-11-23 18:50:07,338 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 18:50:07 INFO - 2015-11-23 18:50:07,338 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,338 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:61.20 Median:61.50 stddev:0.79 (1.3%) stddev-sans-first:0.78 18:50:07 INFO - 2015-11-23 18:50:07,338 DEBUG : BROWSER_OUTPUT: Values: 62.0 61.0 62.0 61.0 60.0 62.0 60.0 61.0 62.0 61.0 18:50:07 INFO - 2015-11-23 18:50:07,339 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,339 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.50 stddev:0.52 (0.7%) stddev-sans-first:0.50 18:50:07 INFO - 2015-11-23 18:50:07,339 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 69.0 70.0 70.0 69.0 69.0 69.0 69.0 69.0 18:50:07 INFO - 2015-11-23 18:50:07,339 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,340 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:0.85 (1.0%) stddev-sans-first:0.71 18:50:07 INFO - 2015-11-23 18:50:07,340 DEBUG : BROWSER_OUTPUT: Values: 83.0 86.0 85.0 84.0 85.0 85.0 84.0 85.0 84.0 84.0 18:50:07 INFO - 2015-11-23 18:50:07,340 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,340 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:121.50 stddev:5.08 (4.2%) stddev-sans-first:4.71 18:50:07 INFO - 2015-11-23 18:50:07,340 DEBUG : BROWSER_OUTPUT: Values: 115.0 120.0 120.0 119.0 126.0 121.0 120.0 123.0 134.0 122.0 18:50:07 INFO - 2015-11-23 18:50:07,341 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,341 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.70 Median:149.50 stddev:2.21 (1.5%) stddev-sans-first:2.26 18:50:07 INFO - 2015-11-23 18:50:07,341 DEBUG : BROWSER_OUTPUT: Values: 148.0 150.0 151.0 151.0 149.0 148.0 149.0 148.0 155.0 148.0 18:50:07 INFO - 2015-11-23 18:50:07,341 DEBUG : BROWSER_OUTPUT: 18:50:07 INFO - 2015-11-23 18:50:07,341 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:67.50 stddev:2.75 (4.1%) stddev-sans-first:1.73 18:50:07 INFO - 2015-11-23 18:50:07,341 DEBUG : BROWSER_OUTPUT: Values: 74.0 66.0 66.0 65.0 69.0 68.0 67.0 65.0 67.0 70.0 18:50:07 INFO - 2015-11-23 18:50:07,341 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:50:07 INFO - 2015-11-23 18:50:07,342 DEBUG : BROWSER_OUTPUT: 18:50:08 INFO - 2015-11-23 18:50:08,405 INFO : Browser exited with error code: 0 18:50:08 INFO - 2015-11-23 18:50:08,458 INFO : Completed test kraken (00:01:00) 18:50:08 INFO - 2015-11-23 18:50:08,459 INFO : Starting test v8_7 18:50:08 INFO - 2015-11-23 18:50:08,459 DEBUG : operating with platform_type : linux_ 18:50:08 INFO - 2015-11-23 18:50:08,459 INFO : Initialising browser for v8_7 test... 18:50:08 INFO - 2015-11-23 18:50:08,465 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvpety5/profile http://localhost:34444/getInfo.html 18:50:12 INFO - 2015-11-23 18:50:12,929 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:50:12 INFO - 2015-11-23 18:50:12,929 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:50:12 INFO - 2015-11-23 18:50:12,929 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:50:12 INFO - 2015-11-23 18:50:12,929 DEBUG : BROWSER_OUTPUT: __metrics 18:50:14 INFO - 2015-11-23 18:50:14,053 INFO : Browser initialized. 18:50:14 INFO - 2015-11-23 18:50:14,054 INFO : Running cycle 1/1 for v8_7 test... 18:50:14 INFO - 2015-11-23 18:50:14,054 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpvpety5/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:50:25 INFO - 2015-11-23 18:50:25,828 DEBUG : BROWSER_OUTPUT: RSS: Main: 140447744 18:50:25 INFO - 2015-11-23 18:50:25,829 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,298 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:34444/tests/v8_7/run.html (next: http://localhost:34444/tests/v8_7/run.html) 18:50:47 INFO - 2015-11-23 18:50:47,557 DEBUG : BROWSER_OUTPUT: RSS: Main: 139616256 18:50:47 INFO - 2015-11-23 18:50:47,557 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,557 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:50:47 INFO - 2015-11-23 18:50:47,557 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:50:47 INFO - 2015-11-23 18:50:47,557 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:50:47 INFO - 2015-11-23 18:50:47,557 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:50:47 INFO - 2015-11-23 18:50:47,557 DEBUG : BROWSER_OUTPUT: |0;Richards;185.42555164101614 18:50:47 INFO - 2015-11-23 18:50:47,557 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.34880123743233 18:50:47 INFO - 2015-11-23 18:50:47,558 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.6901408450704 18:50:47 INFO - 2015-11-23 18:50:47,558 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5025.125628140703 18:50:47 INFO - 2015-11-23 18:50:47,558 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1048.2180293501049 18:50:47 INFO - 2015-11-23 18:50:47,558 DEBUG : BROWSER_OUTPUT: |5;Earley;710.7320540156361 18:50:47 INFO - 2015-11-23 18:50:47,558 DEBUG : BROWSER_OUTPUT: |6;Boyer;12156.626506024097 18:50:47 INFO - 2015-11-23 18:50:47,558 DEBUG : BROWSER_OUTPUT: |7;RegExp;24853.658536585364 18:50:47 INFO - 2015-11-23 18:50:47,558 DEBUG : BROWSER_OUTPUT: |8;Splay;551.611141452758 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: __startTimestamp1448333447550__endTimestamp 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:185.43 Median:185.43 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: Values: 185.4 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,559 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.35 Median:193.35 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: Values: 193.3 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.69 Median:281.69 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: Values: 281.7 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5025.13 Median:5025.13 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: Values: 5025.1 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1048.22 Median:1048.22 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: Values: 1048.2 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:710.73 Median:710.73 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: Values: 710.7 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,560 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12156.63 Median:12156.63 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: Values: 12156.6 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24853.66 Median:24853.66 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: Values: 24853.7 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:551.61 Median:551.61 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: Values: 551.6 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: Values: 4845.4 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:50:47 INFO - 2015-11-23 18:50:47,561 DEBUG : BROWSER_OUTPUT: 18:50:48 INFO - 2015-11-23 18:50:48,421 INFO : Browser exited with error code: 0 18:50:48 INFO - 2015-11-23 18:50:48,434 INFO : Completed test v8_7 (00:00:39) 18:50:48 INFO - 2015-11-23 18:50:48,549 INFO : Completed test suite (00:20:11) 18:50:48 INFO - 2015-11-23 18:50:48,549 DEBUG : Working with test: dromaeo_css 18:50:48 INFO - 2015-11-23 18:50:48,550 DEBUG : Generating results file: dromaeo_css 18:50:48 INFO - 2015-11-23 18:50:48,551 DEBUG : Working with test: kraken 18:50:48 INFO - 2015-11-23 18:50:48,551 DEBUG : Generating results file: kraken 18:50:48 INFO - 2015-11-23 18:50:48,551 INFO : javascript benchmark 18:50:48 INFO - 2015-11-23 18:50:48,552 DEBUG : Working with test: v8_7 18:50:48 INFO - 2015-11-23 18:50:48,552 DEBUG : Generating results file: v8_7 18:50:48 INFO - 2015-11-23 18:50:48,552 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:48 INFO - 2015-11-23 18:50:48,692 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 18:50:48 INFO - 2015-11-23 18:50:48,693 DEBUG : process_Request line: dromaeo_css 7039.80 graph.html#tests=[[72,64,43]] 18:50:48 INFO - 2015-11-23 18:50:48,693 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:48 INFO - 2015-11-23 18:50:48,800 DEBUG : process_Request line: kraken 1515.40 graph.html#tests=[[232,64,43]] 18:50:48 INFO - 2015-11-23 18:50:48,800 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:50:48 INFO - 2015-11-23 18:50:48,906 DEBUG : process_Request line: v8_7 21426.03 graph.html#tests=[[230,64,43]] 18:50:48 INFO - 2015-11-23 18:50:48,908 INFO : javascript benchmark 18:50:48 INFO - 2015-11-23 18:50:48,911 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3250.138713240991, 3292.045306861012, 3315.535824984504, 3341.33173365327, 3352.882649107282, 5250.264962905197, 5434.918476222855, 5483.479069488402, 5509.834704958847, 5544.8336549903515, 5375.6774593524415, 5483.287172667548, 5483.561315094793, 5503.724813759316, 5541.556675465963, 4637.304404339359, 4945.258211268311, 5075.238714192882, 5081.2124120761155, 5089.211172268286, 4782.378290822188, 4965.851024469272, 5053.6715113517685, 5063.46833582474, 5083.593312534988, 4908.901821963558, 4967.230079337691, 5080.923786143174, 5082.872928176826, 5105.846824595268, 4898.044881248158, 4915.827946021908, 4922.901541969176, 4939.703617782909, 4965.329680493123, 4881.804727810883, 4898.338724272249, 4918.2376731606655, 4961.107000739866, 4992.825251116194, 5148.3564554430695, 5169.586433085382, 5187.0922588546855, 5197.922031169535, 5228.5555727763185, 5821.155932389822, 5869.79455719052, 5872.442117998783, 5877.265341832271, 5881.823545293648, 5738.770449182028, 5840.328362238354, 5858.502027327682, 5887.234659494281, 5915.319738230116, 5542.75057622408, 5631.718414079321, 5639.548836093103, 5643.80246691372, 5663.235463212455, 6050.122732203806, 6069.393060693896, 6116.847078823065, 6120.051392034242, 6122.0, 5693.60144789869, 5750.942490575125, 5787.160861675034, 5868.765249390062, 5891.7054147292465, 5968.522518198533, 6005.549583781234, 6008.21893153896, 6021.57848950578, 6022.307434644984, 5920.733566989496, 5976.671283079386, 5984.311804142492, 5986.5210783137245, 5992.610480318765, 5756.222909907193, 5812.7674893003805, 5826.679532625746, 5856.092305692625, 5925.762969481172, 4495.235809912323, 4578.381918440968, 4591.44902611682, 4605.217113090783, 4644.140833945731, 4006.939895901564, 4077.55146933837, 4101.077257617012, 4121.258173528794, 4140.337545992687, 5531.723413829292, 5534.08687566547, 5539.335279766373, 5595.636283641632, 5631.155326700946, 5935.347111817697, 5958.6424814511265, 5976.85057873548, 5978.850528736816, 5980.700964951822, 5516.724163791875, 5635.408282130399, 5721.370649228582, 5741.971290143606, 5746.942530574641, 5083.339165908408, 5085.110105731482, 5086.491350864958, 5103.081445339801, 5118.10433174193, 6087.8478038048515, 6134.4172303631085, 6140.232470941132, 6142.109394137914, 6142.355052719578, 5737.053386191232, 5776.96014717355, 5831.1253312001845, 5889.322727886177, 5928.851778705567, 6138.171346868295, 6224.813255602339, 6238.438540531374, 6245.781397651061, 6262.968685156545, 5851.268591426072, 5888.823335299948, 5913.733881975408, 5916.3492015877355, 5925.733341999534, 5665.830025099253, 5789.710514474344, 5898.469137777621, 5975.55183361241, 6007.219061522149, 5817.476427121579, 5826.126081087871, 5844.590878638454, 5859.44335288147, 5862.149988251765, 5781.884362312814, 5828.6502809831545, 5834.241548598655, 5839.970800145972, 5856.560757943086, 5559.444055594408, 5587.972060139836, 5640.351359593699, 5682.886342273048, 5733.885322293448, 5802.100674395476, 5864.765409383577, 5869.765209391748, 5890.587658864009, 5897.764089436546, 5435.972820135874, 5648.209250704823, 5672.4894759471845, 5714.942850571441, 5757.8560535986935], "unit": "ms", "name": "dojo.html", "value": 5445.686412121886}, {"lowerIsBetter": false, "replicates": [582.0, 596.1623918394652, 598.5122125467743, 603.3393434189563, 603.7041849493748, 38287.80856095719, 38668.226635467276, 38752.80623596878, 38863.0, 39103.21793564127, 41760.0, 42185.0, 42230.366544501856, 42317.57682423175, 42333.7883310583, 8535.530545819962, 8550.230479256865, 8697.130286971316, 8699.39104262702, 8707.085755995624, 8486.078074716681, 8607.655693772242, 8612.181842724962, 8621.05168431472, 8643.265322447569, 8488.702895398663, 8626.223639872442, 8645.30837532999, 8648.65405383784, 8662.566871656423, 8600.827983440358, 8631.870521942175, 8679.435836670596, 8689.7827554311, 8698.434601750865, 8448.281896038845, 8552.486850788973, 8586.055533891267, 8599.097094805018, 8624.137586241388, 39366.40950385747, 39523.40714889279, 39683.40474892879, 39741.40387894155, 39993.60006399928, 3280.3603297357026, 3527.6119626841282, 4002.5597184309704, 4018.9799051004557, 4184.623383895434, 29058.41883162325, 29694.851525742448, 30106.54840177399, 30404.69595304041, 30789.38421231563, 3493.196564790087, 3626.274745050974, 3631.5823680276844, 3666.8899933002053, 3741.8690345837763, 269476.0, 270847.0, 272958.0, 273456.0, 274260.0, 41752.37371439431, 42371.57628423738, 58848.41151588515, 59143.70428147918, 59298.0, 40832.59167408347, 40942.59057409447, 40971.0, 41117.1776564467, 41124.79437602793, 11984.940075299568, 12116.878831211752, 12166.0, 12211.206271592495, 12213.328266945226, 9710.902890971, 9756.46339451337, 9774.31579789408, 9782.0, 9789.167920726817, 14205.92897035529, 14259.78610320846, 14270.500532481314, 14329.928350358181, 14358.425662973574, 5913.408659134138, 5981.132735753378, 5991.101334799728, 6029.939700603026, 6068.696565171724, 892.0633334998225, 913.5158366065996, 975.9024097590184, 979.4270351844109, 1039.9844002339971, 28324.43351133007, 28601.284967875552, 28674.569881451796, 28872.42255154885, 33169.170770730445, 990.9752256193652, 995.785906030207, 1021.371856308365, 1032.442481060219, 1123.646051493777, 14700.926495367456, 14925.776113358308, 15038.924805375904, 15176.241187940559, 15207.543773686808, 380684.0, 388805.0, 389080.0, 389692.0, 390244.0, 52575.0, 52751.73624131932, 53162.734186328824, 53223.20165197525, 53310.733446333295, 1244.9688757781128, 1363.0935427940508, 1364.6861221918816, 1369.3632460905528, 1380.758367285741, 27817.357351051618, 28626.856865715537, 28645.28386790347, 28982.710172898845, 29046.854765726035, 44691.32963005493, 45102.32346514871, 45104.0, 45122.0, 45141.54858451374, 288629.0, 288953.0, 291979.0, 292698.0, 293415.0, 27965.860170699016, 28392.716072839008, 31648.367032659677, 31820.8408957963, 32580.837095814368, 40782.59217407788, 40835.0, 40898.1820363597, 41830.37254441125, 41940.790296048326, 33485.33029339448, 34507.48238776471, 34874.47688284729, 35235.29529409449, 35301.78309417841, 35223.29553408969, 35526.46710299399, 35546.64453355433, 35616.287674245854, 35740.0, 34231.65768342285, 34825.6069757217, 35014.0, 35062.47406288959, 35129.473057904666, 31315.84342078275, 31405.214869628442, 31719.682803171672, 31940.84029579837, 32268.0, 24448.0, 24505.877470612533, 24690.50618987646, 24738.010479580615, 24876.253712388658], "unit": "ms", "name": "ext.html", "value": 17714.315192783757}, {"lowerIsBetter": false, "replicates": [361.67449295633924, 364.0589077235347, 365.4463487815959, 365.99085022874425, 367.9484872117904, 16583.751243731338, 16935.91532042341, 17537.91231043843, 17661.55846103848, 18304.359347422844, 11089.112870970323, 11122.888771112286, 11136.0, 11790.938815506603, 16612.58468538287, 4808.759562021903, 4945.109880221558, 5043.142665746814, 5347.1177255752755, 5368.033753924292, 5310.15037593985, 5370.006548788487, 5371.704556249405, 5371.7582708778, 5377.569794416457, 5038.168702164138, 5088.872778180556, 5197.974010129963, 5440.319960004999, 5440.619156659035, 5156.0, 5156.123459011977, 5161.587073034186, 5180.766865491043, 5193.16909294513, 4968.503149685039, 5089.05852417304, 5093.032323858455, 5125.846224613268, 5139.100657384943, 12459.314737689423, 12468.002559795284, 12501.874981250163, 16810.83189168105, 19922.501937451536, 2096.6121267565395, 2119.205298013245, 2170.728658917635, 2171.6091103601348, 2173.500094978148, 13322.733545329136, 13400.5309814157, 13401.0, 13437.328133593379, 13629.795553066711, 1781.5546113471632, 1792.0681245752266, 1802.2790883646514, 1821.6174603333316, 1824.42530602861, 61459.0, 61779.14662560086, 61957.38042619561, 65520.3447965519, 66980.33019669789, 9891.851622225673, 10034.297599167989, 10144.44205568686, 10153.13698335635, 10201.795964080677, 12817.551385701456, 12893.226406415644, 12901.354932253349, 12933.482660693468, 12954.352282386031, 6974.128233970754, 7162.570245785269, 7193.064901562763, 7350.93411455336, 7379.077615298088, 6897.965510172417, 6934.618595977169, 6939.132608423947, 6978.581285122909, 6997.930020699728, 4180.268453020709, 4271.551487093872, 4289.506706728704, 4312.116016216668, 4343.543927887589, 1751.9474415767547, 1753.386314789839, 1754.526277904984, 1755.3943889358152, 1792.7759030121235, 828.2873588995966, 916.7937214126902, 924.5562130177552, 985.6599473181741, 1046.6493724602192, 16348.918255408647, 16721.581960451087, 16912.154392280336, 17054.403095891583, 17352.91323543374, 909.7179874239007, 949.7673070097732, 953.5661274120258, 971.5967873332629, 980.2305190425482, 9731.951340243395, 9871.950640246752, 9926.702198934043, 9936.254780891462, 10056.446895420675, 537159.0, 544269.0, 547631.0, 565909.0, 573485.0, 77777.0, 79830.0, 81547.0, 81851.0, 83666.0, 1081.5025088459397, 1092.836074588818, 1094.6442406218011, 1096.681962230944, 1103.4386343324268, 15058.247087645355, 15529.68940621204, 15563.37746490173, 15650.9217453912, 15680.372785088925, 31893.68106318907, 32040.198995024377, 33335.16662083467, 33382.83308583441, 33696.15759606029, 46398.53601464077, 46815.0, 49515.75242123767, 49897.251541226186, 49901.0, 9161.908380916373, 9567.569459374485, 9617.182539484222, 9657.517124143906, 9726.75683107928, 11328.603498877499, 15553.766693499832, 16792.412265570645, 17360.82639173592, 18564.628707425505, 13706.177629341873, 13911.304434778018, 14118.223497707313, 14450.0, 14679.192644404233, 14039.929800350932, 14071.0, 14110.223937683526, 14934.626634333794, 15200.0, 14811.555653330417, 15344.769828452805, 15496.612584685474, 15567.76648350253, 15595.688086238439, 9676.080772326617, 9736.805263894825, 9870.407775533491, 9878.654247101316, 9909.355891867006, 14962.476313328982, 15052.924735376253, 15211.847881521486, 15342.76985845191, 15411.845881541041], "unit": "ms", "name": "jquery.html", "value": 8781.23171642675}, {"lowerIsBetter": false, "replicates": [1487.9107253564785, 1550.9844901550985, 1624.9512514624566, 1687.6287216812304, 1785.1252886085813, 7660.961695191523, 7833.686652533892, 8010.91989080109, 8014.3187829034605, 8036.035675718915, 7742.574158421285, 7748.263914928087, 7807.063152421717, 7807.804804879881, 7819.061712594483, 2201.086549082129, 2238.6194346961056, 2263.6830843681896, 2264.0264686184933, 2267.1044937249776, 2272.9204477843277, 2289.610766169747, 2353.211674089182, 2360.221127028085, 2362.527494501098, 2215.3686199433164, 2259.0737797503025, 2276.8633881967053, 2278.943026424336, 2280.0, 2251.6960210371717, 2258.3563684349965, 2283.1209984156135, 2302.435903203709, 2307.5846347657416, 2310.202979971907, 2312.352541288442, 2336.5210131923086, 2342.145117032288, 2344.4724936889234, 7576.621168941531, 7733.574653394061, 7754.0, 7780.105287891908, 7805.882911756328, 4691.6481263905, 4782.450018247877, 4791.640626952958, 4796.496367881357, 4835.879103022417, 2876.5972763813083, 2876.913692589226, 2944.4258369617933, 2947.204254851178, 2984.820910745362, 911.9224865886407, 926.4904302633524, 930.9255259579218, 949.3164921256684, 951.8001219743862, 10591.205659575562, 10616.681499555025, 10619.522121504473, 10633.361998280128, 10652.733681657943, 4177.039280965395, 4233.428487154257, 4266.104118135165, 4279.786010699452, 4279.828806847753, 8823.735287941372, 8825.735227943173, 8829.735107946772, 8834.028256891737, 8846.646134154562, 4733.621310295168, 4734.928976065362, 4750.501197374226, 4759.214729569651, 4766.165920963817, 4307.719998200107, 4337.934930976038, 4350.8042138103865, 4364.5635436456105, 4370.38814565961, 3535.2045789697113, 3551.8224088795973, 3556.4309710446205, 3557.5197348358156, 3563.928721425557, 1085.766560189563, 1104.2932523185168, 1116.8771435142128, 1121.1143196874377, 1121.8934201250868, 960.3757557587485, 966.0966995858895, 1022.0290723812408, 1127.8477405550145, 1346.4344975110478, 7096.929030709627, 7141.107361579802, 7142.964285178541, 7155.034070400533, 7186.568805871664, 593.5044238061181, 599.1970759182716, 599.5743022454106, 602.524006035236, 751.8872169174558, 5654.529444248191, 5658.519025882846, 5718.456746609066, 5768.394318596487, 5788.21859049023, 9158.633654653873, 9486.193673537826, 9490.952545237367, 9550.331476796557, 9609.423434593948, 9179.81640367189, 9231.584578693975, 9335.346525743133, 9376.953115234517, 9487.430754154773, 7410.740624078131, 7411.88882166768, 7496.587687677121, 7497.287757663123, 7508.699652013967, 7260.818479538054, 7280.271972802678, 7381.11426628808, 7411.147718012282, 7416.406687465098, 8256.091829898587, 8267.917320826715, 8359.456635318686, 8515.74452766418, 8534.658613655385, 8393.32853371741, 8580.14198580137, 8619.655213791379, 8642.611082501176, 8658.523781192094, 7042.401395881408, 7077.964610176916, 7154.35610795031, 7157.355837974607, 7170.103737032871, 7584.582847943416, 7695.268949449794, 7730.613469326398, 7740.380769538536, 7770.961145194238, 6887.414569761627, 7081.681324340314, 7159.677814498465, 7261.128664560287, 7340.192578816219, 6852.534103355452, 6924.272951340238, 6931.618760968196, 6977.511574189758, 7000.229974702676, 6887.758928437481, 6936.34104760047, 6936.965315173392, 6953.33943275388, 6953.617551034742, 6383.93616063852, 6417.71120299597, 6448.290688024407, 6486.416222539994, 6498.285188629153, 6300.991841305369, 6301.275353334425, 6440.613563186036, 6452.677366131581, 6453.774117905851], "unit": "ms", "name": "mootools.html", "value": 4408.15321700584}, {"lowerIsBetter": false, "replicates": [321.7651114686279, 329.49916127486216, 336.9157710572357, 342.3290350912212, 348.2338854520055, 6178.96910515448, 6297.716602752876, 6354.173957385545, 6397.00846368813, 6399.744010239585, 6046.12331211974, 6256.09286653435, 6272.059191121322, 6276.278228003781, 6285.245770507546, 4805.0, 4805.663603547753, 4821.662483626147, 4822.5900798432, 4829.348038014876, 4830.855074347775, 4834.371531700874, 4840.104580652591, 4845.709257444564, 4850.514948505156, 4684.13343531446, 4776.283557466373, 4788.688735232199, 4797.0405918816205, 4797.328374027639, 4767.475577686451, 4773.615651461089, 4780.474147843769, 4782.97608511957, 4794.664373493857, 4180.24755544002, 4188.68584856137, 4194.3918131870705, 4204.894877628053, 4214.2414365414215, 5889.381614930457, 5904.586678932476, 5946.762129514815, 5953.642781433128, 5963.612365196248, 5374.355077390699, 5559.749811258503, 5568.443155684399, 5578.609497335188, 5590.720463976825, 6580.901286480706, 6608.438282745972, 6610.438112760421, 6630.900536491957, 6636.93363066368, 4599.5630415110845, 4623.2371658676275, 4634.930476042862, 4635.049814787961, 4644.419447569054, 6737.528373013891, 6814.761483348059, 6907.792766217021, 6916.170059592881, 6920.2387737348345, 4759.4764575896625, 4760.500147484464, 4761.738104404221, 4765.0946320199055, 4765.737884416321, 4816.975915120472, 4819.445763737215, 4820.807167713322, 4822.083804077214, 4839.862632281411, 5554.611177217636, 5568.248286481273, 5569.10894256917, 5571.275734154615, 5574.0, 6540.607563546202, 6622.966885165543, 6638.867222655616, 6639.535232533676, 6726.630035348103, 1618.8300228476076, 1650.3976048742363, 1655.6357601327688, 1659.0460485221047, 1659.0957927929308, 1081.0648788797632, 1081.3079629037422, 1091.37930173692, 1109.1681239070697, 1109.4840898982013, 6349.492040636738, 6351.396617321347, 6377.808665740035, 6413.615183089029, 6435.292117867031, 6700.463962882957, 6731.259561448237, 6787.796366108926, 6789.456843452512, 6810.897836532456, 6285.685715714196, 6349.428551430394, 6391.169148010729, 6393.808185754435, 6403.615783053032, 3697.7411581189594, 3702.1114932416026, 3721.6092310307567, 3721.7208709346905, 3735.103575142001, 7054.400375967997, 7069.293070692993, 7075.610841403669, 7078.186008608973, 7082.362587367162, 6922.238553759083, 6952.5480843745, 6981.441484681213, 6984.720611175598, 7003.894941575881, 7238.239984801626, 7246.420286377077, 7246.746363877239, 7249.420046396275, 7260.056192694916, 6924.61914594682, 6961.825954351182, 6963.059986901703, 6976.720931162697, 6995.965020174866, 6754.223264324469, 6863.725450982104, 6887.242403335729, 6906.723731050702, 6914.101166848278, 7227.096612923385, 7236.131664200329, 7239.239879812754, 7251.782446526613, 7252.709891604488, 4636.188666983197, 4670.602998745009, 4677.2750223715375, 4679.3916790816975, 4682.993156471375, 6802.319768023158, 6942.757003505055, 6945.548539344926, 6953.617551034539, 6957.9304206959305, 6713.697883595348, 6740.662966851736, 6743.12339395895, 6752.392284694204, 6761.86476270462, 6701.430378417889, 6730.596164230162, 6730.899036514358, 6742.898856517058, 6756.358145976124, 6480.837979050561, 6599.637019963948, 6613.503987200883, 6613.900791488035, 6615.669216539058, 6705.865882682221, 6760.121184246016, 6764.763233286812, 6773.085633439423, 6787.21946976104, 6523.836904077436, 6540.411362977355, 6544.738210471718, 6547.508936829661, 6547.639879806466], "unit": "ms", "name": "prototype.html", "value": 5020.273863161776}, {"lowerIsBetter": false, "replicates": [746.985060298794, 819.1948399683472, 837.782176634075, 854.5342788180441, 859.965601375945, 14150.221737804417, 14310.57068287953, 14497.275136243199, 14520.128792272471, 14572.854271457281, 15170.089794612331, 15311.6937661247, 15330.616734581614, 15355.846441535581, 15373.308201130949, 823.6746485138367, 826.6528058215533, 834.995825020874, 835.3567752830317, 836.1638361638362, 839.7480755773274, 847.9788005299855, 851.6678495386805, 852.4970267542149, 853.8591132463137, 879.4942907827992, 882.4131952251745, 889.9955500222522, 890.5636238243279, 893.2005854759985, 879.3580686099183, 880.1286726141107, 884.3809333466567, 885.2033170146855, 885.8936927568701, 646.9644169570672, 650.2164891305965, 650.4178760009764, 650.6746626686656, 650.8633187030729, 14395.640108997359, 14410.207438590873, 14537.418503259856, 14543.709125817424, 14548.127112373291, 12642.936785316106, 12798.424070916832, 12869.22784632925, 12872.806907896389, 13363.465461381533, 10089.697309080739, 10539.525721342558, 10610.363378197333, 10654.893451065545, 10868.67393978182, 3699.408094704834, 3756.1173124315765, 3788.034051316924, 3871.457995880579, 3885.8834234972715, 50460.74769626165, 50541.74729126331, 50655.24017139746, 50674.49325506735, 50735.49264507345, 10098.596056157672, 10472.476376181161, 10496.737581560523, 10583.0, 10889.0, 19195.712064319046, 19232.23071077169, 19726.506837328896, 19737.111829967685, 19751.20995160206, 7466.178720340758, 7512.962435187789, 7686.923130768732, 7969.681212751425, 8025.438219324591, 4173.728707633994, 4255.1915136124035, 4275.46556680415, 4291.377750226262, 4317.740935543878, 3101.8138911665374, 3102.8758849646215, 3104.5343198520404, 3124.7812653114065, 3127.5934128563595, 539.5090467674476, 555.303094616254, 557.4648337595953, 559.7117484495488, 567.4750855458684, 544.3060098374525, 593.8752861898963, 622.5579838314757, 628.8805127025851, 628.9371062893765, 7983.880241796378, 8420.53687047206, 8448.873266901, 8471.915280847114, 8734.0829212933, 553.941836107211, 602.8251806975928, 620.454000479575, 663.2571519897746, 679.9354061364262, 3794.29805485986, 3993.6206060424215, 4033.878983630496, 4046.3121269383687, 4067.5322337930925, 59554.0, 59571.404285957455, 60084.0, 60311.396886030576, 60441.69779151076, 49953.25070123951, 50159.24761128586, 50411.74794126006, 50895.0, 50953.74523127435, 46269.305960410624, 46782.76608616867, 47256.7637161812, 47276.05447891023, 48111.27833082437, 7993.920060799318, 8036.83926321482, 8093.797655058671, 8120.675172993014, 8285.627146778288, 36787.816060919526, 37775.81112094422, 38067.61932380641, 38684.6131538681, 38919.22161556697, 46840.0, 47340.52659473361, 47411.0, 47416.52583474121, 47939.76030119827, 10964.122870170208, 11340.20618556693, 11447.198696091195, 11447.656570302905, 11816.76366472683, 11876.76246475083, 12039.217450865666, 12075.758484830078, 12540.933177208522, 12817.615471535868, 11829.467673954521, 12274.324912129561, 12445.191062580903, 12455.377231138587, 12564.623061308175, 12042.698932526757, 12172.57395991136, 12310.07674424404, 12512.124151309321, 12697.87302127004, 10626.787464250827, 10717.517711702836, 11924.642260732191, 11962.222455540363, 12564.0, 9293.628254869729, 10048.296619236584, 10049.0, 10281.897181028095, 10458.581656733646, 8630.611622477129, 8881.156290152425, 9206.907930920874, 9365.344425890395, 10278.486075696335], "unit": "ms", "name": "yui.html", "value": 6492.450419848569}], "name": "dromaeo_css", "value": 7039.796562125007}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 103.0, 110.0, 111.0, 111.0, 122.0, 110.0, 110.0, 111.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 110.9}, {"lowerIsBetter": true, "replicates": [162.0, 125.0, 157.0, 164.0, 128.0, 149.0, 153.0, 133.0, 164.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.0}, {"lowerIsBetter": true, "replicates": [163.0, 163.0, 162.0, 161.0, 160.0, 161.0, 162.0, 164.0, 170.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 162.9}, {"lowerIsBetter": true, "replicates": [78.0, 71.0, 71.0, 72.0, 71.0, 71.0, 151.0, 76.0, 72.0, 83.0], "unit": "ms", "name": "audio-fft", "value": 81.6}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 138.0, 92.0, 93.0, 93.0, 133.0, 94.0, 93.0, 143.0], "unit": "ms", "name": "audio-oscillator", "value": 106.6}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 147.0, 148.0, 147.0, 146.0, 148.0, 148.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.8}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 62.0, 61.0, 60.0, 62.0, 60.0, 61.0, 62.0, 61.0], "unit": "ms", "name": "json-parse-financial", "value": 61.2}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 69.0, 70.0, 70.0, 69.0, 69.0, 69.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [83.0, 86.0, 85.0, 84.0, 85.0, 85.0, 84.0, 85.0, 84.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.5}, {"lowerIsBetter": true, "replicates": [115.0, 120.0, 120.0, 119.0, 126.0, 121.0, 120.0, 123.0, 134.0, 122.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.0}, {"lowerIsBetter": true, "replicates": [148.0, 150.0, 151.0, 151.0, 149.0, 148.0, 149.0, 148.0, 155.0, 148.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.7}, {"lowerIsBetter": true, "replicates": [74.0, 66.0, 66.0, 65.0, 69.0, 68.0, 67.0, 65.0, 67.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.7}], "name": "kraken", "value": 1515.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [185.42555164101614], "unit": "score", "name": "Richards", "value": 190.38368600000004}, {"lowerIsBetter": false, "replicates": [193.34880123743233], "unit": "score", "name": "DeltaBlue", "value": 341.96229600000015}, {"lowerIsBetter": false, "replicates": [281.6901408450704], "unit": "score", "name": "Encrypt", "value": 944.9425500000004}, {"lowerIsBetter": false, "replicates": [5025.125628140703], "unit": "score", "name": "Decrypt", "value": 52.970019}, {"lowerIsBetter": false, "replicates": [1048.2180293501049], "unit": "score", "name": "RayTrace", "value": 705.9495059999997}, {"lowerIsBetter": false, "replicates": [710.7320540156361], "unit": "score", "name": "Earley", "value": 937.7134409999999}, {"lowerIsBetter": false, "replicates": [12156.626506024097], "unit": "score", "name": "Boyer", "value": 54.82302180376615}, {"lowerIsBetter": false, "replicates": [24853.658536585364], "unit": "score", "name": "RegExp", "value": 36.65395976447495}, {"lowerIsBetter": false, "replicates": [551.611141452758], "unit": "score", "name": "Splay", "value": 147.73269405940596}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 21426.030516601015}]} 18:50:48 INFO - RETURN: dromaeo_css: 7039.80 18:50:48 INFO - RETURN: kraken: 1515.40 18:50:48 INFO - RETURN: v8_7: 21426.03 18:50:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21426.03"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1515.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7039.80"}}} 18:50:49 INFO - Return code: 0 18:50:49 INFO - # TBPL SUCCESS # 18:50:49 INFO - Running post-action listener: _resource_record_post_action 18:50:49 INFO - Running post-run listener: _resource_record_post_run 18:50:50 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 24576; Write bytes: 509612032; Read time: 60; Write time: 337428 18:50:50 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 164270080; Read time: 0; Write time: 131632 18:50:50 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 24576; Write bytes: 345341952; Read time: 60; Write time: 205796 18:50:50 INFO - Running post-run listener: _upload_blobber_files 18:50:50 INFO - Blob upload gear active. 18:50:50 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:50:50 INFO - Copying logs to upload dir... 18:50:50 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1270.440268 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-11-23 18:50:49.715512) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:49.719493) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010171 build_url: 'https://queue.taskcluster.net/v1/task/nqRi5K-KSFWVYD8BPUkQgA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:49.771073) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:49.771390) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448331445.402915-264586266 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004520 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:50:49.821523) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:50:49.821960) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:50:49.822371) ========= ========= Total master_lag: 0.19 =========