builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1450737821.5 results: success (0) buildid: 20151221122129 builduid: 642bee336c0f4900a6b7a50ce08654c0 revision: c6be663fa194 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.496801) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.497264) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.497590) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005876 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.553168) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.553492) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.567078) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.567487) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004620 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.617687) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.618176) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.618757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.619226) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-21 14:43:41-- 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% 35.3M=0s 2015-12-21 14:43:42 (35.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.225776 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.857638) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.857985) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013464 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.907347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:41.907695) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c6be663fa194 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c6be663fa194 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-21 14:43:42,146 Setting DEBUG logging. 2015-12-21 14:43:42,146 attempt 1/10 2015-12-21 14:43:42,146 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c6be663fa194?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-21 14:43:42,469 unpacking tar archive at: mozilla-beta-c6be663fa194/testing/mozharness/ program finished with exit code 0 elapsedTime=0.715179 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:42.636008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:42.636346) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:42.645143) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:42.645489) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 14:43:42.645908) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2015-12-21 14:43:42.646222) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:43:42 INFO - MultiFileLogger online at 20151221 14:43:42 in /builds/slave/test-pgo 14:43:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:43:42 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:43:42 INFO - {'append_to_log': False, 14:43:42 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:43:42 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:43:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:43:42 INFO - 'branch': 'Mozilla-Beta', 14:43:42 INFO - 'buildbot_json_path': 'buildprops.json', 14:43:42 INFO - 'config_files': ('talos/linux_config.py',), 14:43:42 INFO - 'default_actions': ('clobber', 14:43:42 INFO - 'read-buildbot-config', 14:43:42 INFO - 'download-and-extract', 14:43:42 INFO - 'populate-webroot', 14:43:42 INFO - 'create-virtualenv', 14:43:42 INFO - 'install', 14:43:42 INFO - 'run-tests'), 14:43:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:43:42 INFO - 'download_minidump_stackwalk': True, 14:43:42 INFO - 'download_symbols': 'ondemand', 14:43:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:43:42 INFO - 'tooltool.py': '/tools/tooltool.py', 14:43:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:43:42 INFO - '/tools/misc-python/virtualenv.py')}, 14:43:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:43:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:43:42 INFO - 'installer_path': 'installer.exe', 14:43:42 INFO - 'log_level': 'info', 14:43:42 INFO - 'log_name': 'talos', 14:43:42 INFO - 'log_to_console': True, 14:43:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:43:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:43:42 INFO - 'opt_config_files': (), 14:43:42 INFO - 'pip_index': False, 14:43:42 INFO - 'sps_profile': False, 14:43:42 INFO - 'sps_profile_interval': 0, 14:43:42 INFO - 'suite': 'dromaeojs', 14:43:42 INFO - 'system_bits': '64', 14:43:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:43:42 INFO - 'title': 'talos-linux64-ix-054', 14:43:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:43:42 INFO - 'use_talos_json': True, 14:43:42 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:43:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:43:42 INFO - 'work_dir': 'build'} 14:43:42 INFO - ##### 14:43:42 INFO - ##### Running clobber step. 14:43:42 INFO - ##### 14:43:42 INFO - Running pre-action listener: _resource_record_pre_action 14:43:42 INFO - Running main action method: clobber 14:43:42 INFO - rmtree: /builds/slave/test-pgo/build 14:43:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:43:43 INFO - Running post-action listener: _resource_record_post_action 14:43:43 INFO - ##### 14:43:43 INFO - ##### Running read-buildbot-config step. 14:43:43 INFO - ##### 14:43:43 INFO - Running pre-action listener: _resource_record_pre_action 14:43:43 INFO - Running main action method: read_buildbot_config 14:43:43 INFO - Using buildbot properties: 14:43:43 INFO - { 14:43:43 INFO - "properties": { 14:43:43 INFO - "buildnumber": 39, 14:43:43 INFO - "product": "firefox", 14:43:43 INFO - "script_repo_revision": "production", 14:43:43 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:43:43 INFO - "repository": "", 14:43:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:43:43 INFO - "buildid": "20151221122129", 14:43:43 INFO - "slavename": "talos-linux64-ix-054", 14:43:43 INFO - "pgo_build": "True", 14:43:43 INFO - "basedir": "/builds/slave/test-pgo", 14:43:43 INFO - "project": "", 14:43:43 INFO - "platform": "ubuntu64_hw", 14:43:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:43:43 INFO - "slavebuilddir": "test", 14:43:43 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:43:43 INFO - "branch": "mozilla-beta", 14:43:43 INFO - "repo_path": "releases/mozilla-beta", 14:43:43 INFO - "stage_platform": "linux64-pgo", 14:43:43 INFO - "builduid": "642bee336c0f4900a6b7a50ce08654c0", 14:43:43 INFO - "revision": "c6be663fa194" 14:43:43 INFO - }, 14:43:43 INFO - "sourcestamp": { 14:43:43 INFO - "repository": "", 14:43:43 INFO - "hasPatch": false, 14:43:43 INFO - "project": "", 14:43:43 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:43:43 INFO - "changes": [ 14:43:43 INFO - { 14:43:43 INFO - "category": null, 14:43:43 INFO - "files": [ 14:43:43 INFO - { 14:43:43 INFO - "url": null, 14:43:43 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2" 14:43:43 INFO - } 14:43:43 INFO - ], 14:43:43 INFO - "repository": "", 14:43:43 INFO - "rev": "c6be663fa194", 14:43:43 INFO - "who": "sendchange", 14:43:43 INFO - "when": 1450737702, 14:43:43 INFO - "number": 7382948, 14:43:43 INFO - "comments": "Bug 1233237 - Add Nvidia Network Access Manager .dlls to Windows blocklist. r=aklotz a=ritu", 14:43:43 INFO - "project": "", 14:43:43 INFO - "at": "Mon 21 Dec 2015 14:41:42", 14:43:43 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:43:43 INFO - "revlink": "", 14:43:43 INFO - "properties": [ 14:43:43 INFO - [ 14:43:43 INFO - "buildid", 14:43:43 INFO - "20151221122129", 14:43:43 INFO - "Change" 14:43:43 INFO - ], 14:43:43 INFO - [ 14:43:43 INFO - "builduid", 14:43:43 INFO - "642bee336c0f4900a6b7a50ce08654c0", 14:43:43 INFO - "Change" 14:43:43 INFO - ], 14:43:43 INFO - [ 14:43:43 INFO - "pgo_build", 14:43:43 INFO - "True", 14:43:43 INFO - "Change" 14:43:43 INFO - ] 14:43:43 INFO - ], 14:43:43 INFO - "revision": "c6be663fa194" 14:43:43 INFO - } 14:43:43 INFO - ], 14:43:43 INFO - "revision": "c6be663fa194" 14:43:43 INFO - } 14:43:43 INFO - } 14:43:43 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2. 14:43:43 INFO - Running post-action listener: _resource_record_post_action 14:43:43 INFO - ##### 14:43:43 INFO - ##### Running download-and-extract step. 14:43:43 INFO - ##### 14:43:43 INFO - Running pre-action listener: _resource_record_pre_action 14:43:43 INFO - Running main action method: download_and_extract 14:43:43 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/test_packages.json 14:43:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:43:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:43:43 INFO - Downloaded 1270 bytes. 14:43:43 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:43:43 INFO - Using the following test package requirements: 14:43:43 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:43:43 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:43:43 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:43:43 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:43:43 INFO - u'jsshell-linux-x86_64.zip'], 14:43:43 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:43:43 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:43:43 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:43:43 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:43:43 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 14:43:43 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:43:43 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 14:43:43 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:43:43 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:43:43 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 14:43:43 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 14:43:43 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:43:43 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:43:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:43:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:43:45 INFO - Downloaded 22571576 bytes. 14:43:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:43:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:43:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:43:46 INFO - Return code: 0 14:43:46 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:46 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:43:46 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:43:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:43:47 INFO - Downloaded 22571576 bytes. 14:43:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 14:43:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 14:43:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 14:43:48 INFO - Return code: 0 14:43: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')]} 14:43:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:43:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:43:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:43:49 INFO - Downloaded 11131970 bytes. 14:43:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 14:43:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 14:43:49 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 14:43:49 INFO - Return code: 0 14:43:49 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')]} 14:43:49 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:43:49 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:43:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:43:50 INFO - Downloaded 56623739 bytes. 14:43:50 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:43:50 INFO - mkdir: /builds/slave/test-pgo/properties 14:43:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:43:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:43:50 INFO - Contents: 14:43:50 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2 14:43:50 INFO - Running post-action listener: _resource_record_post_action 14:43:50 INFO - Running post-action listener: set_extra_try_arguments 14:43:50 INFO - ##### 14:43:50 INFO - ##### Running populate-webroot step. 14:43:50 INFO - ##### 14:43:50 INFO - Running pre-action listener: _resource_record_pre_action 14:43:50 INFO - Running main action method: populate_webroot 14:43:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:43:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:43:50 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 14:43:50 INFO - '../fennec_ids.txt'], 14:43:50 INFO - 'tests': ['tcheck2']}, 14:43:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:43:50 INFO - '--tppagecycles', 14:43:50 INFO - '7'], 14:43:50 INFO - 'tests': ['tsvgm']}}, 14:43:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:43:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:43:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:43:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:43:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:43:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:50 INFO - 'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:43:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:50 INFO - 'tests': ['damp', 'tps']}, 14:43:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:50 INFO - 'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['damp', 'tps']}, 14:43:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:43:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:43:50 INFO - 'other': {'tests': ['a11yr', 14:43:50 INFO - 'ts_paint', 14:43:50 INFO - 'tpaint', 14:43:50 INFO - 'sessionrestore', 14:43:50 INFO - 'sessionrestore_no_auto_restore']}, 14:43:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['a11yr', 14:43:50 INFO - 'ts_paint', 14:43:50 INFO - 'tpaint', 14:43:50 INFO - 'sessionrestore', 14:43:50 INFO - 'sessionrestore_no_auto_restore']}, 14:43:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['a11yr', 14:43:50 INFO - 'ts_paint', 14:43:50 INFO - 'tpaint', 14:43:50 INFO - 'sessionrestore', 14:43:50 INFO - 'sessionrestore_no_auto_restore']}, 14:43:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['a11yr', 14:43:50 INFO - 'ts_paint', 14:43:50 INFO - 'tpaint', 14:43:50 INFO - 'sessionrestore', 14:43:50 INFO - 'sessionrestore_no_auto_restore']}, 14:43:50 INFO - 'other_l64': {'tests': ['a11yr', 14:43:50 INFO - 'ts_paint', 14:43:50 INFO - 'tpaint', 14:43:50 INFO - 'sessionrestore', 14:43:50 INFO - 'sessionrestore_no_auto_restore']}, 14:43:50 INFO - 'other_nol64': {'tests': ['a11yr', 14:43:50 INFO - 'ts_paint', 14:43:50 INFO - 'tpaint', 14:43:50 INFO - 'sessionrestore', 14:43:50 INFO - 'sessionrestore_no_auto_restore']}, 14:43:50 INFO - 'svgr': {'tests': ['tsvgx', 14:43:50 INFO - 'tsvgr_opacity', 14:43:50 INFO - 'tart', 14:43:50 INFO - 'tscrollx', 14:43:50 INFO - 'cart']}, 14:43:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['tsvgx', 14:43:50 INFO - 'tsvgr_opacity', 14:43:50 INFO - 'tart', 14:43:50 INFO - 'tscrollx', 14:43:50 INFO - 'cart']}, 14:43:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:50 INFO - 'tests': ['tp5o']}, 14:43:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:50 INFO - 'talos_options': ['--e10s'], 14:43:50 INFO - 'tests': ['tp5o']}, 14:43:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:50 INFO - 'talos_options': ['--xperf_path', 14:43:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:43:50 INFO - 'tests': ['tp5n']}, 14:43:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:43:50 INFO - 'talos_options': ['--e10s', 14:43:50 INFO - '--xperf_path', 14:43:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:43:50 INFO - 'tests': ['tp5n']}}, 14:43:50 INFO - 'talos.zip': {'path': '', 14:43:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:43:50 INFO - Running post-action listener: _resource_record_post_action 14:43:50 INFO - ##### 14:43:50 INFO - ##### Running create-virtualenv step. 14:43:50 INFO - ##### 14:43:50 INFO - Running pre-action listener: _resource_record_pre_action 14:43:50 INFO - Running main action method: create_virtualenv 14:43:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:43:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 14:43:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:43:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:43:50 INFO - Using real prefix '/usr' 14:43:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:43:50 INFO - Installing distribute.............................................................................................................................................................................................done. 14:43:52 INFO - Installing pip.................done. 14:43:52 INFO - Return code: 0 14:43:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:43:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 14:43:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:43:52 INFO - Using env: {'DISPLAY': ':0', 14:43:52 INFO - 'HOME': '/home/cltbld', 14:43:52 INFO - 'LANG': 'en_US.UTF-8', 14:43:52 INFO - 'LANGUAGE': 'en_US:en', 14:43:52 INFO - 'LOGNAME': 'cltbld', 14:43:52 INFO - 'MAIL': '/var/mail/cltbld', 14:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:52 INFO - 'MOZ_NO_REMOTE': '1', 14:43:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:52 INFO - 'NO_EM_RESTART': '1', 14:43:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:43:52 INFO - 'PWD': '/builds/slave/test-pgo', 14:43:52 INFO - 'SHELL': '/bin/bash', 14:43:52 INFO - 'SHLVL': '1', 14:43:52 INFO - 'TERM': 'linux', 14:43:52 INFO - 'TMOUT': '86400', 14:43:52 INFO - 'USER': 'cltbld', 14:43:52 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:52 INFO - '_': '/tools/buildbot/bin/python'} 14:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:52 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 14:43:52 INFO - Cleaning up... 14:43:52 INFO - Return code: 0 14:43:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:43:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 14:43:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:43:52 INFO - Using env: {'DISPLAY': ':0', 14:43:52 INFO - 'HOME': '/home/cltbld', 14:43:52 INFO - 'LANG': 'en_US.UTF-8', 14:43:52 INFO - 'LANGUAGE': 'en_US:en', 14:43:52 INFO - 'LOGNAME': 'cltbld', 14:43:52 INFO - 'MAIL': '/var/mail/cltbld', 14:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:52 INFO - 'MOZ_NO_REMOTE': '1', 14:43:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:52 INFO - 'NO_EM_RESTART': '1', 14:43:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:43:52 INFO - 'PWD': '/builds/slave/test-pgo', 14:43:52 INFO - 'SHELL': '/bin/bash', 14:43:52 INFO - 'SHLVL': '1', 14:43:52 INFO - 'TERM': 'linux', 14:43:52 INFO - 'TMOUT': '86400', 14:43:52 INFO - 'USER': 'cltbld', 14:43:52 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:52 INFO - '_': '/tools/buildbot/bin/python'} 14:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:53 INFO - Downloading/unpacking psutil>=0.7.1 14:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:54 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 14:43:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 14:43:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:43:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:43:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:43:54 INFO - Installing collected packages: psutil 14:43:54 INFO - Running setup.py install for psutil 14:43:55 INFO - building 'psutil._psutil_linux' extension 14:43:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:43:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:43:55 INFO - building 'psutil._psutil_posix' extension 14:43:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:43:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:43:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:43:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:43:55 INFO - Successfully installed psutil 14:43:55 INFO - Cleaning up... 14:43:55 INFO - Return code: 0 14:43:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:43:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 14:43:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:43:55 INFO - Using env: {'DISPLAY': ':0', 14:43:55 INFO - 'HOME': '/home/cltbld', 14:43:55 INFO - 'LANG': 'en_US.UTF-8', 14:43:55 INFO - 'LANGUAGE': 'en_US:en', 14:43:55 INFO - 'LOGNAME': 'cltbld', 14:43:55 INFO - 'MAIL': '/var/mail/cltbld', 14:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:55 INFO - 'MOZ_NO_REMOTE': '1', 14:43:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:55 INFO - 'NO_EM_RESTART': '1', 14:43:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:43:55 INFO - 'PWD': '/builds/slave/test-pgo', 14:43:55 INFO - 'SHELL': '/bin/bash', 14:43:55 INFO - 'SHLVL': '1', 14:43:55 INFO - 'TERM': 'linux', 14:43:55 INFO - 'TMOUT': '86400', 14:43:55 INFO - 'USER': 'cltbld', 14:43:55 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:55 INFO - '_': '/tools/buildbot/bin/python'} 14:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:43:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 14:43:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:43:57 INFO - Installing collected packages: mozsystemmonitor 14:43:57 INFO - Running setup.py install for mozsystemmonitor 14:43:57 INFO - Successfully installed mozsystemmonitor 14:43:57 INFO - Cleaning up... 14:43:57 INFO - Return code: 0 14:43:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:43:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 14:43:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:43:57 INFO - Using env: {'DISPLAY': ':0', 14:43:57 INFO - 'HOME': '/home/cltbld', 14:43:57 INFO - 'LANG': 'en_US.UTF-8', 14:43:57 INFO - 'LANGUAGE': 'en_US:en', 14:43:57 INFO - 'LOGNAME': 'cltbld', 14:43:57 INFO - 'MAIL': '/var/mail/cltbld', 14:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:57 INFO - 'MOZ_NO_REMOTE': '1', 14:43:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:43:57 INFO - 'NO_EM_RESTART': '1', 14:43:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:43:57 INFO - 'PWD': '/builds/slave/test-pgo', 14:43:57 INFO - 'SHELL': '/bin/bash', 14:43:57 INFO - 'SHLVL': '1', 14:43:57 INFO - 'TERM': 'linux', 14:43:57 INFO - 'TMOUT': '86400', 14:43:57 INFO - 'USER': 'cltbld', 14:43:57 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:57 INFO - '_': '/tools/buildbot/bin/python'} 14:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:57 INFO - Downloading/unpacking blobuploader==1.2.4 14:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:59 INFO - Downloading blobuploader-1.2.4.tar.gz 14:43:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 14:43:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:43:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 14:43:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:43:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:43:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:00 INFO - Downloading docopt-0.6.1.tar.gz 14:44:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 14:44:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:44:00 INFO - Installing collected packages: blobuploader, requests, docopt 14:44:00 INFO - Running setup.py install for blobuploader 14:44:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:44:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:44:00 INFO - Running setup.py install for requests 14:44:00 INFO - Running setup.py install for docopt 14:44:00 INFO - Successfully installed blobuploader requests docopt 14:44:00 INFO - Cleaning up... 14:44:00 INFO - Return code: 0 14:44:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:44: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')]} 14:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44: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')]} 14:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:00 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:44:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:44:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:44:00 INFO - Using env: {'DISPLAY': ':0', 14:44:00 INFO - 'HOME': '/home/cltbld', 14:44:00 INFO - 'LANG': 'en_US.UTF-8', 14:44:00 INFO - 'LANGUAGE': 'en_US:en', 14:44:00 INFO - 'LOGNAME': 'cltbld', 14:44:00 INFO - 'MAIL': '/var/mail/cltbld', 14:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:00 INFO - 'MOZ_NO_REMOTE': '1', 14:44:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:00 INFO - 'NO_EM_RESTART': '1', 14:44:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:44:00 INFO - 'PWD': '/builds/slave/test-pgo', 14:44:00 INFO - 'SHELL': '/bin/bash', 14:44:00 INFO - 'SHLVL': '1', 14:44:00 INFO - 'TERM': 'linux', 14:44:00 INFO - 'TMOUT': '86400', 14:44:00 INFO - 'USER': 'cltbld', 14:44:00 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:00 INFO - '_': '/tools/buildbot/bin/python'} 14:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:44:00 INFO - Running setup.py (path:/tmp/pip-kficcH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:44:01 INFO - Running setup.py (path:/tmp/pip-OE5_2W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:44:01 INFO - Running setup.py (path:/tmp/pip-qkQBBG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:44:01 INFO - Running setup.py (path:/tmp/pip-LuVaJq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:44:01 INFO - Running setup.py (path:/tmp/pip-Z_3WiP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:44:01 INFO - Running setup.py (path:/tmp/pip-5I3DD3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:44:01 INFO - Running setup.py (path:/tmp/pip-2g39dh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:44:01 INFO - Running setup.py (path:/tmp/pip-Z4PXC7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:44:01 INFO - Running setup.py (path:/tmp/pip-tAZYFK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:44:01 INFO - Running setup.py (path:/tmp/pip-0o0Huv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:44:01 INFO - Running setup.py (path:/tmp/pip-qfPA3S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:44:01 INFO - Running setup.py (path:/tmp/pip-F2eRGW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:44:01 INFO - Running setup.py (path:/tmp/pip-zBHqwz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:44:01 INFO - Running setup.py (path:/tmp/pip-pRL7Y0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:44:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:44:01 INFO - Running setup.py (path:/tmp/pip-DRaZPo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:44:02 INFO - Running setup.py (path:/tmp/pip-qwFtnS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:44:02 INFO - Running setup.py (path:/tmp/pip-fKKqkb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:44:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:44:02 INFO - Running setup.py install for manifestparser 14:44:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:44:02 INFO - Running setup.py install for mozcrash 14:44:02 INFO - Running setup.py install for mozdebug 14:44:02 INFO - Running setup.py install for mozdevice 14:44:02 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:44:02 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:44:02 INFO - Running setup.py install for mozfile 14:44:02 INFO - Running setup.py install for mozhttpd 14:44:02 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:44:02 INFO - Running setup.py install for mozinfo 14:44:02 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:44:02 INFO - Running setup.py install for mozInstall 14:44:02 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:44:02 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:44:02 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:44:02 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:44:03 INFO - Running setup.py install for mozleak 14:44:03 INFO - Running setup.py install for mozlog 14:44:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:44:03 INFO - Running setup.py install for moznetwork 14:44:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:44:03 INFO - Running setup.py install for mozprocess 14:44:03 INFO - Running setup.py install for mozprofile 14:44:03 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:44:03 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:44:03 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:44:03 INFO - Running setup.py install for mozrunner 14:44:03 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:44:03 INFO - Running setup.py install for mozscreenshot 14:44:03 INFO - Running setup.py install for moztest 14:44:03 INFO - Running setup.py install for mozversion 14:44:03 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:44:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:44:03 INFO - Cleaning up... 14:44:04 INFO - Return code: 0 14:44:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:44:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 14:44:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:44:04 INFO - Using env: {'DISPLAY': ':0', 14:44:04 INFO - 'HOME': '/home/cltbld', 14:44:04 INFO - 'LANG': 'en_US.UTF-8', 14:44:04 INFO - 'LANGUAGE': 'en_US:en', 14:44:04 INFO - 'LOGNAME': 'cltbld', 14:44:04 INFO - 'MAIL': '/var/mail/cltbld', 14:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:04 INFO - 'MOZ_NO_REMOTE': '1', 14:44:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:04 INFO - 'NO_EM_RESTART': '1', 14:44:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:44:04 INFO - 'PWD': '/builds/slave/test-pgo', 14:44:04 INFO - 'SHELL': '/bin/bash', 14:44:04 INFO - 'SHLVL': '1', 14:44:04 INFO - 'TERM': 'linux', 14:44:04 INFO - 'TMOUT': '86400', 14:44:04 INFO - 'USER': 'cltbld', 14:44:04 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:04 INFO - '_': '/tools/buildbot/bin/python'} 14:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:44:04 INFO - Running setup.py (path:/tmp/pip-ZQZx2r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:44:04 INFO - Running setup.py (path:/tmp/pip-d6usWx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:44:04 INFO - Running setup.py (path:/tmp/pip-s8R_VT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:44:04 INFO - Running setup.py (path:/tmp/pip-Rrvq7Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 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)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:44:04 INFO - Running setup.py (path:/tmp/pip-INd2rz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:44:04 INFO - Running setup.py (path:/tmp/pip-YxtEjB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:44:04 INFO - Running setup.py (path:/tmp/pip-LoLwaV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 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)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:44:04 INFO - Running setup.py (path:/tmp/pip-ji48wz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:44:04 INFO - Running setup.py (path:/tmp/pip-1tdoIb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:44:04 INFO - Running setup.py (path:/tmp/pip-Hq6fnl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:44:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:44:04 INFO - Running setup.py (path:/tmp/pip-YGyr6Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 14:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:44:05 INFO - Running setup.py (path:/tmp/pip-H9OHUP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 14:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:44:05 INFO - Running setup.py (path:/tmp/pip-Qco5Op-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 14:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:44:05 INFO - Running setup.py (path:/tmp/pip-KKe7PJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 14:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:44:05 INFO - Running setup.py (path:/tmp/pip-L837xl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 14:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:44:05 INFO - Running setup.py (path:/tmp/pip-ylEwtz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 14:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:44:05 INFO - Running setup.py (path:/tmp/pip-BmJUv2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 14:44:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:07 INFO - Downloading blessings-1.5.1.tar.gz 14:44:07 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 14:44:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:44:07 INFO - Installing collected packages: blessings 14:44:07 INFO - Running setup.py install for blessings 14:44:07 INFO - Successfully installed blessings 14:44:07 INFO - Cleaning up... 14:44:07 INFO - Return code: 0 14:44:07 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:44:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:44:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:44:07 INFO - Reading from file tmpfile_stdout 14:44:07 INFO - Current package versions: 14:44:07 INFO - argparse == 1.2.1 14:44:07 INFO - blessings == 1.5.1 14:44:07 INFO - blobuploader == 1.2.4 14:44:07 INFO - docopt == 0.6.1 14:44:07 INFO - manifestparser == 1.1 14:44:07 INFO - mozInstall == 1.12 14:44:07 INFO - mozcrash == 0.16 14:44:07 INFO - mozdebug == 0.1 14:44:07 INFO - mozdevice == 0.46 14:44:07 INFO - mozfile == 1.2 14:44:07 INFO - mozhttpd == 0.7 14:44:07 INFO - mozinfo == 0.8 14:44:07 INFO - mozleak == 0.1 14:44:07 INFO - mozlog == 3.0 14:44:07 INFO - moznetwork == 0.27 14:44:07 INFO - mozprocess == 0.22 14:44:07 INFO - mozprofile == 0.27 14:44:07 INFO - mozrunner == 6.11 14:44:07 INFO - mozscreenshot == 0.1 14:44:07 INFO - mozsystemmonitor == 0.0 14:44:07 INFO - moztest == 0.7 14:44:07 INFO - mozversion == 1.4 14:44:07 INFO - psutil == 3.1.1 14:44:07 INFO - requests == 1.2.3 14:44:07 INFO - wsgiref == 0.1.2 14:44:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:44: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')]} 14:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44: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')]} 14:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:44:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 14:44:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:44:07 INFO - Using env: {'DISPLAY': ':0', 14:44:07 INFO - 'HOME': '/home/cltbld', 14:44:07 INFO - 'LANG': 'en_US.UTF-8', 14:44:07 INFO - 'LANGUAGE': 'en_US:en', 14:44:07 INFO - 'LOGNAME': 'cltbld', 14:44:07 INFO - 'MAIL': '/var/mail/cltbld', 14:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:07 INFO - 'MOZ_NO_REMOTE': '1', 14:44:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:07 INFO - 'NO_EM_RESTART': '1', 14:44:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:44:07 INFO - 'PWD': '/builds/slave/test-pgo', 14:44:07 INFO - 'SHELL': '/bin/bash', 14:44:07 INFO - 'SHLVL': '1', 14:44:07 INFO - 'TERM': 'linux', 14:44:07 INFO - 'TMOUT': '86400', 14:44:07 INFO - 'USER': 'cltbld', 14:44:07 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:07 INFO - '_': '/tools/buildbot/bin/python'} 14:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:44:07 INFO - Cleaning up... 14:44:07 INFO - Return code: 0 14:44:07 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:44: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')]} 14:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44: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')]} 14:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:44: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', 'jsonschema'] in /builds/slave/test-pgo/build 14:44: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 jsonschema 14:44:07 INFO - Using env: {'DISPLAY': ':0', 14:44:07 INFO - 'HOME': '/home/cltbld', 14:44:07 INFO - 'LANG': 'en_US.UTF-8', 14:44:07 INFO - 'LANGUAGE': 'en_US:en', 14:44:07 INFO - 'LOGNAME': 'cltbld', 14:44:07 INFO - 'MAIL': '/var/mail/cltbld', 14:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:07 INFO - 'MOZ_NO_REMOTE': '1', 14:44:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:07 INFO - 'NO_EM_RESTART': '1', 14:44:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:44:07 INFO - 'PWD': '/builds/slave/test-pgo', 14:44:07 INFO - 'SHELL': '/bin/bash', 14:44:07 INFO - 'SHLVL': '1', 14:44:07 INFO - 'TERM': 'linux', 14:44:07 INFO - 'TMOUT': '86400', 14:44:07 INFO - 'USER': 'cltbld', 14:44:07 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:07 INFO - '_': '/tools/buildbot/bin/python'} 14:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:08 INFO - Downloading/unpacking jsonschema 14:44:08 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 14:44:08 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 14:44:08 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 14:44:08 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 14:44:08 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 14:44:08 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 14:44:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:44:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 14:44:09 INFO - Downloading/unpacking functools32 (from jsonschema) 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44:09 INFO - Downloading functools32-3.2.3-2.tar.gz 14:44:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 14:44:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:44:09 INFO - warning: no files found matching '*.txt' 14:44:09 INFO - no previously-included directories found matching 'build' 14:44:09 INFO - no previously-included directories found matching 'dist' 14:44:09 INFO - no previously-included directories found matching '.git*' 14:44:09 INFO - Installing collected packages: jsonschema, functools32 14:44:10 INFO - Running setup.py install for functools32 14:44:10 INFO - warning: no files found matching '*.txt' 14:44:10 INFO - no previously-included directories found matching 'build' 14:44:10 INFO - no previously-included directories found matching 'dist' 14:44:10 INFO - no previously-included directories found matching '.git*' 14:44:10 INFO - Successfully installed jsonschema functools32 14:44:10 INFO - Cleaning up... 14:44:10 INFO - Return code: 0 14:44:10 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:44:10 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')]} 14:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:10 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')]} 14:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:44:10 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f392b1f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27ee600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', '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 14:44:10 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', 'flake8'] in /builds/slave/test-pgo/build 14:44:10 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 flake8 14:44:10 INFO - Using env: {'DISPLAY': ':0', 14:44:10 INFO - 'HOME': '/home/cltbld', 14:44:10 INFO - 'LANG': 'en_US.UTF-8', 14:44:10 INFO - 'LANGUAGE': 'en_US:en', 14:44:10 INFO - 'LOGNAME': 'cltbld', 14:44:10 INFO - 'MAIL': '/var/mail/cltbld', 14:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:10 INFO - 'MOZ_NO_REMOTE': '1', 14:44:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:10 INFO - 'NO_EM_RESTART': '1', 14:44:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:44:10 INFO - 'PWD': '/builds/slave/test-pgo', 14:44:10 INFO - 'SHELL': '/bin/bash', 14:44:10 INFO - 'SHLVL': '1', 14:44:10 INFO - 'TERM': 'linux', 14:44:10 INFO - 'TMOUT': '86400', 14:44:10 INFO - 'USER': 'cltbld', 14:44:10 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:10 INFO - '_': '/tools/buildbot/bin/python'} 14:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:44:10 INFO - Downloading/unpacking flake8 14:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:44:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:44:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 14:44:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:44:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 14:44:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 14:44:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44: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 14:44:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 14:44:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:44:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:44:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:44:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:44:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:44:12 INFO - no previously-included directories found matching 'docs/_build' 14:44:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:44:12 INFO - Running setup.py install for pep8 14:44:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:44:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:44:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:44:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:44:12 INFO - no previously-included directories found matching 'docs/_build' 14:44:12 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:44:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:44:12 INFO - Cleaning up... 14:44:12 INFO - Return code: 0 14:44:12 INFO - Running post-action listener: _resource_record_post_action 14:44:12 INFO - Running post-action listener: _start_resource_monitoring 14:44:12 INFO - Starting resource monitoring. 14:44:12 INFO - ##### 14:44:12 INFO - ##### Running install step. 14:44:12 INFO - ##### 14:44:12 INFO - Running pre-action listener: _resource_record_pre_action 14:44:12 INFO - Running main action method: install 14:44:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:44:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:44:13 INFO - Reading from file tmpfile_stdout 14:44:13 INFO - Detecting whether we're running mozinstall >=1.0... 14:44:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:44:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:44:13 INFO - Reading from file tmpfile_stdout 14:44:13 INFO - Output received: 14:44:13 INFO - Usage: mozinstall [options] installer 14:44:13 INFO - Options: 14:44:13 INFO - -h, --help show this help message and exit 14:44:13 INFO - -d DEST, --destination=DEST 14:44:13 INFO - Directory to install application into. [default: 14:44:13 INFO - "/builds/slave/test-pgo"] 14:44:13 INFO - --app=APP Application being installed. [default: firefox] 14:44:13 INFO - mkdir: /builds/slave/test-pgo/build/application 14:44:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 14:44:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 14:44:27 INFO - Reading from file tmpfile_stdout 14:44:27 INFO - Output received: 14:44:27 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:44:27 INFO - Running post-action listener: _resource_record_post_action 14:44:27 INFO - ##### 14:44:27 INFO - ##### Running run-tests step. 14:44:27 INFO - ##### 14:44:27 INFO - Running pre-action listener: _resource_record_pre_action 14:44:27 INFO - Running main action method: run_tests 14:44:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:44:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:44:27 INFO - Python 2.7.3 14:44:27 INFO - Return code: 0 14:44:27 INFO - grabbing minidump binary from tooltool 14:44:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:44:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287d350>, '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 0x287e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x287e6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:44:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 14:44:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:44:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:44:27 INFO - Return code: 0 14:44:27 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:44:27 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:44:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:44:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:44:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:44:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:44:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:44:28 INFO - Return code: 0 14:44:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:44:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost 14:44:28 INFO - Using env: {'DISPLAY': ':0', 14:44:28 INFO - 'HOME': '/home/cltbld', 14:44:28 INFO - 'LANG': 'en_US.UTF-8', 14:44:28 INFO - 'LANGUAGE': 'en_US:en', 14:44:28 INFO - 'LOGNAME': 'cltbld', 14:44:28 INFO - 'MAIL': '/var/mail/cltbld', 14:44:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:44:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:44:28 INFO - 'MOZ_NO_REMOTE': '1', 14:44:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:44:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:44:28 INFO - 'NO_EM_RESTART': '1', 14:44:28 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:44:28 INFO - 'PWD': '/builds/slave/test-pgo', 14:44:28 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:44:28 INFO - 'SHELL': '/bin/bash', 14:44:28 INFO - 'SHLVL': '1', 14:44:28 INFO - 'TERM': 'linux', 14:44:28 INFO - 'TMOUT': '86400', 14:44:28 INFO - 'USER': 'cltbld', 14:44:28 INFO - 'XDG_SESSION_COOKIE': '752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511', 14:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:44:28 INFO - '_': '/tools/buildbot/bin/python'} 14:44:28 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] with output_timeout 3600 14:44:28 INFO - mozversion INFO | application_buildid: 20151221122129 14:44:28 INFO - mozversion INFO | application_changeset: c6be663fa1946241927de6d960e152f5c1a1f8f7 14:44:28 INFO - mozversion INFO | application_display_name: Nightly 14:44:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:44:28 INFO - mozversion INFO | application_name: Firefox 14:44:28 INFO - mozversion INFO | application_remotingname: firefox 14:44:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:44:28 INFO - mozversion INFO | application_vendor: Mozilla 14:44:28 INFO - mozversion INFO | application_version: 44.0 14:44:28 INFO - mozversion INFO | platform_buildid: 20151221122129 14:44:28 INFO - mozversion INFO | platform_changeset: c6be663fa1946241927de6d960e152f5c1a1f8f7 14:44:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:44:28 INFO - mozversion INFO | platform_version: 43.0 14:44:28 INFO - 2015-12-21 14:44:28,662 DEBUG : using testdate: 1450737868 14:44:28 INFO - 2015-12-21 14:44:28,662 DEBUG : actual date: 1450737868 14:44:28 INFO - 2015-12-21 14:44:28,670 INFO : starting webserver on 'localhost:52214' 14:44:28 INFO - 2015-12-21 14:44:28,671 INFO : Starting test suite talos-linux64-ix-054 14:44:28 INFO - 2015-12-21 14:44:28,672 INFO : Starting test dromaeo_css 14:44:28 INFO - 2015-12-21 14:44:28,672 DEBUG : operating with platform_type : linux_ 14:44:28 INFO - 2015-12-21 14:44:28,672 INFO : Initialising browser for dromaeo_css test... 14:44:28 INFO - 2015-12-21 14:44:28,688 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppRbMCk/profile http://localhost:52214/getInfo.html 14:44:32 INFO - 2015-12-21 14:44:32,685 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:44:32 INFO - 2015-12-21 14:44:32,685 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:44:32 INFO - 2015-12-21 14:44:32,685 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:44:32 INFO - 2015-12-21 14:44:32,685 DEBUG : BROWSER_OUTPUT: __metrics 14:44:32 INFO - 2015-12-21 14:44:32,766 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:44:34 INFO - 2015-12-21 14:44:34,096 INFO : Browser initialized. 14:44:34 INFO - 2015-12-21 14:44:34,097 INFO : Running cycle 1/1 for dromaeo_css test... 14:44:34 INFO - 2015-12-21 14:44:34,097 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppRbMCk/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:44:45 INFO - 2015-12-21 14:44:45,629 DEBUG : BROWSER_OUTPUT: RSS: Main: 128847872 14:44:45 INFO - 2015-12-21 14:44:45,630 DEBUG : BROWSER_OUTPUT: 14:45:36 INFO - 2015-12-21 14:45:36,430 DEBUG : BROWSER_OUTPUT: 1450737936426 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] 14:47:44 INFO - 2015-12-21 14:47:44,526 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52214/tests/dromaeo/cssquery-ext.html) 14:47:45 INFO - 2015-12-21 14:47:45,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 236703744 14:47:45 INFO - 2015-12-21 14:47:45,599 DEBUG : BROWSER_OUTPUT: 14:50:47 INFO - 2015-12-21 14:50:47,567 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/dromaeo/cssquery-ext.html (next: http://localhost:52214/tests/dromaeo/cssquery-jquery.html) 14:50:48 INFO - 2015-12-21 14:50:48,133 DEBUG : BROWSER_OUTPUT: RSS: Main: 211910656 14:50:48 INFO - 2015-12-21 14:50:48,133 DEBUG : BROWSER_OUTPUT: 14:53:49 INFO - 2015-12-21 14:53:49,829 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52214/tests/dromaeo/cssquery-mootools.html) 14:53:50 INFO - 2015-12-21 14:53:50,390 DEBUG : BROWSER_OUTPUT: RSS: Main: 210972672 14:53:50 INFO - 2015-12-21 14:53:50,390 DEBUG : BROWSER_OUTPUT: 14:56:52 INFO - 2015-12-21 14:56:52,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52214/tests/dromaeo/cssquery-prototype.html) 14:56:52 INFO - 2015-12-21 14:56:52,667 DEBUG : BROWSER_OUTPUT: RSS: Main: 192487424 14:56:52 INFO - 2015-12-21 14:56:52,667 DEBUG : BROWSER_OUTPUT: 14:59:55 INFO - 2015-12-21 14:59:55,021 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52214/tests/dromaeo/cssquery-yui.html) 14:59:55 INFO - 2015-12-21 14:59:55,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 194453504 14:59:55 INFO - 2015-12-21 14:59:55,584 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,644 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/dromaeo/cssquery-yui.html (next: http://localhost:52214/tests/dromaeo/cssquery-dojo.html) 15:02:57 INFO - 2015-12-21 15:02:57,890 DEBUG : BROWSER_OUTPUT: RSS: Main: 236425216 15:02:57 INFO - 2015-12-21 15:02:57,890 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,890 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:02:57 INFO - 2015-12-21 15:02:57,890 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:02:57 INFO - 2015-12-21 15:02:57,891 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:02:57 INFO - 2015-12-21 15:02:57,891 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:02:57 INFO - 2015-12-21 15:02:57,894 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3331.500274958756;3391.457366821306;3410.880619178329;3416.726661867049;3438.724902007839;5585.329760428749;5588.748506317215;5599.41206173351;5615.522680572156;5619.887602247953;5568.914061757959;5644.350899646542;5646.096624540064;5662.150677398392;5673.461021202979;4936.901261974758;5071.163258062425;5099.413567439737;5161.870953226162;5206.713630750326;5157.226416037587;5164.096283150452;5169.09540830355;5170.224466330062;5173.017126745906;4985.6260780441435;5070.8732281693065;5088.160453525183;5109.489051094898;5162.741862906859;5082.796688132489;5206.531412172903;5212.661177023481;5214.191800270964;5220.791168353261;5144.305518754958;5176.042432150027;5201.765920533585;5229.189475631283;5252.133397989327;5220.947790522084;5260.6580572263065;5325.4142044375085;5391.083515802323;5472.945270547323;6059.7273122709585;6076.848078798022;6082.361352058015;6094.238220222473;6096.786612468542;6109.663968481732;6118.449339559461;6128.724207410677;6131.417515336036;6133.785317513865;5494.066008778477;5544.251526043973;5613.831585052455;5618.325800903917;5644.887102257973;6168.537359697951;6195.256569211723;6203.348648391944;6221;6222.191115155091;5778.739956701958;6045.3652366501765;6056.424639659225;6066.48434883031;6066.908996365058;5945.554083443759;5979.641221526722;6071.150038994544;6077.997130473511;6101.511879049665;5946.494547963472;5980.700964951735;5984.102384642339;5984.461398474158;5987.880242395127;5870.73581688825;5979.611325263844;5980.102984552352;5983.04271316596;6032.6983650818165;4600.95399046012;4625.259958406638;4626.306054091913;4626.467956185014;4632.536746325408;4050.5341885682933;4138.13099249155;4144.295469770146;4161.0637606538285;4161.667066634662;5677.943220567824;5734.5125664319;5736.942630573724;5738.483536481736;5743.684097374685;6015.30823955242;6039.516838652898;6062.090686396988;6063.363346848606;6066.787662431793;5539.418361072029;5617.719114044281;5645.661260324394;5727.713614319351;5759.625624334405;5205.089109405839;5215.713135777569;5224.268602395668;5224.634275600747;5228.398734145622;6001.3098493672915;6197.659128747872;6205.472534834499;6208.286047104551;6216.409441103088;5966.463018328372;6002.429769171922;6097.634141951497;6099.146119543178;6124.877502449837;6185.907211391743;6235.875282494416;6240.469560087262;6242.875142497034;6243.43809057187;5714.914276285942;5822.243108395882;6003.969980150071;6019.187409699634;6022.337542870368;5841.18223448726;5933.139694744152;5940.079287710412;5947.167396564571;5968.582199246011;5878.676672782867;5881.264841894763;5947.464728174485;5949.52403807703;5956.344802071855;5735.8566035849435;5811.651300921958;5930.644161350333;5940.851478712894;5947.613405128653;5801.36185019639;5870.970645146917;5872.677002764889;5880;5883.146943693226;5939.524838013035;5967.254093238345;5972.402759724167;5979.850503737267;5990.88018239624;5542.944570554243;5637.46444087811;5712.885742285214;5825.79609713658;5852.741660542833 15:02:57 INFO - 2015-12-21 15:02:57,894 DEBUG : BROWSER_OUTPUT: |1;ext.html;559.974801133949;611.8623309755304;620.4601996263252;632.9367063293669;648.0182523476931;41701.37447938283;42270.36594451082;42358.15283694324;42413;42429.78785106074;45983;46599.301010484785;46605.766971165096;46652.3002154968;46685.29972050413;8212.260896519312;8320.875186872201;9331.580078896432;9484.288678349118;10803.4058126803;11093.667189984313;11165.888341116566;11186.44067796607;11204.719882002933;11217.831732523979;11070.889291107067;11201.66395008151;11211.439428028607;11215.831762523569;11226.270292431007;11077.390743509104;11089.05743011841;11172.329660220414;11188.545489086408;11191.104711623051;10960.890391096067;11016.834747478793;11084.057855082328;11098.944505277423;11104.833427498594;44895.10209795819;45246.773766131286;45277;45370.546294536965;45473.772631136635;3562.3943929532043;3576.678098971079;3601.8199090045655;3665.6151104133946;3737.5328083989502;33178;33579.6642033579;33703.6629633703;33721.83139084289;34001;3953.9802300988313;4039.252738243405;4042.151148258869;4115.403266526367;4152.688548358885;318864;319596;320988;321383;321407;59726.402735972086;60657.39342606517;65769.34230657728;71045.64477177581;72273.30223535454;66997;67044;67188.66405667941;67411.66294168498;67478.32521674721;18731;18823.623527529373;18921.432357029313;18950.715739263924;19007.809921900604;13022.869771302356;13038.41327140281;13052.412641431158;13078.542251021168;13199.274039927896;20554.17783288652;20683.4829129273;20729.85985770767;20769.688454673193;20776.792232077787;8081.111077781439;8087.393445491612;8239.505629662239;8267.131951145164;8273.420860539825;1020.5152552537485;1049.5801679328329;1071.5392381276126;1074.1460538871459;1168.7370341673056;29923.251918702208;30054.84972575123;30324.090277291718;30408.087757367313;30712.846435768126;1120.7646394257297;1144.7023773818867;1164.6622479480855;1203.518592562982;1276.997556917815;13995.51015714445;15873.12697801632;15975.920120399323;16117.758233626504;16704.742575197964;400037;401185;401298;402998;403665;57095.71452142796;57407.425925741045;57593.42406575881;57613;57746.71126644424;1393.2058726525986;1467.9045862018934;1494.0587429919299;1495.0207614012838;1508.8868334874708;24432.51134977326;30850.228744280674;31121.172168461722;31355.21611959628;31912.68087319097;54206.18690719721;54840.72579637076;54902.725486372314;54943.72528137494;54973;359808;360981;361967;362990;363335;35613.46579801357;35625;36188;36197.63802361942;36542.45186322154;47060;47929.28106078342;48337.75831120822;48660;49064.509354906;39818;40904.590954090076;41217;41744.37383439312;41866.37200441935;39073.804630976665;40437;40808.38787418132;41428.79285603673;41589.79205103955;36354.45468317925;36966.63033369632;37118.072048199014;37256;37432.25135497329;32483;34816.20509953465;35515.46726799152;35837.46243806397;36474.635253647124;25808.61287080733;26369.736302637495;26494.205173844817;26545.734542654325;26763.732362676903 15:02:57 INFO - 2015-12-21 15:02:57,894 DEBUG : BROWSER_OUTPUT: |2;jquery.html;381.1063057131027;385.24684242306296;386.5168539325843;386.700307261872;387.6917850309005;18260.90869545652;19803.405897823053;20264.59470810583;20330.89834550827;20341.796582034192;17289.04910229937;17342.56643583911;17824.019678917655;18023.549411264725;19635.803641963576;5711.657300561958;5859.531237501011;5864.70676466175;5872;5872.530197584183;5635.070213414781;5649.209110724501;5690.630109042899;5818.1272809078555;5843.2695913010875;4332.610065094141;4459.955400446002;5611.382747897748;5616.073347897592;6073.301570319403;6133.294671112834;6169.660668663222;6185.845353866144;6199.65901875399;6213.502919766408;5323.201519772027;5345;5352.437994010651;5356.4107948125675;5357.223202635635;21787;21873.67189492159;22012;22311.107555697752;22432.439189020242;2243.562505311465;2355.882205889716;2448.4490989527394;2476.5418397596504;2577.1624222127884;14049.78925316121;14495.637609059753;14549.345279462344;14659.706805863823;15056.247187640574;1848.2606957217085;1961.5390383259924;1971.073595410155;2001.579668269665;2052.8049835265624;66592;67318.3268167317;68188;68532.31467685309;69232.653836731;9904.752381190528;10001.2999090063;10162.796744065077;10175.7456063599;10231.846522302172;12934.611961640978;12938.547150849676;12944;13016.869831301756;13063;8302.6678932842;8457.238848503664;8470;8505.23452889243;8516.403851730443;8862.911370886337;8903.643854245758;8924.419912705653;8938.4190027648;8942;6347.968260158762;6372.330905254881;6381.329960354189;6412.839679008062;6435.967820160869;1669.3572974404942;1711.2812618700175;1759.9472015839544;1790.7045337519164;1836.8714190006838;1005.1506477026901;1043.888259004158;1058.9258751887294;1143.7941170589374;1165.7260543772206;12593.874061259454;12975.675608109685;13112.803307950388;13317;13325.933370333087;501.8770401251717;533.8024930775638;545.9863503412367;554.6256277013;557.9218909352694;5155.32980712505;6822.14755062765;6940.861182776316;6952.826179345456;7034.050403195605;669535;673353;677096;684546;696019;86780;87808;88875;89499;91257;604.5707547641224;606.1967892542399;609.600711533946;610.7312782375741;612.3080918561997;12023.63929082129;12409.069319801232;12410.193337433038;12463.314517701614;12478.875211248134;40868.59131408648;41054.79472602618;41916.16167676568;42139;42272.15455690808;55522;56539;57078;57397.13904291522;58266.7086664564;15547.689046219239;15631.374745010527;15921.68156636884;16120.193990300673;16171.514854554382;19545;19867.410607151105;19882.304119355755;20251.18995240233;20336.69494957547;16683.16584170811;16817.747733784247;17012.829871701124;17324;17324;16435.178241088135;16561.08914009741;16723.74914376261;17044.14779261057;17145.571360716083;13673.794893076412;15033.624159396102;17271.222794974005;17797.644047119244;17844.55388615295;15589.220538972779;15663.295151717972;15687.215639217766;15880.2059897007;16077.35690572364;16220.107894065939;16379.508614741577;16394.26225819817;17013.14934253307;17222.96662200272 15:02:57 INFO - 2015-12-21 15:02:57,895 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1560.4304428883454;1567.9216039198038;1786.624808790154;1823.1340113446115;1847.8635639081965;7260.636968151584;7446;7920.445568810185;8265.256126948567;8288.792780180498;7439.665215065335;7532.246775322479;7590.165081841007;7608.0489938757655;7618.619069046553;2361.539499797536;2396.3290278721984;2397.7242617098996;2398.352444839892;2399.2682231919257;2110.67284570891;2155.8706477611395;2349.7650234976536;2380.071772008918;2396.8202384821207;2280.703508543895;2290.6678531612906;2293.289080385086;2294.609916314222;2296.2422400607843;2352.447174913894;2355.258093700496;2355.8468699534646;2362.314928670692;2364.9763502365013;2312.9190478333176;2358.8466749661216;2359.245041586693;2362.0669835414915;2364.491634298634;8018.67925282988;8076.8788468173025;8082.43422960393;8093.028836539594;8096.3118134958595;4937.728424936627;5010.799568017275;5012.498750124995;5017;5017.322661440695;3276.803391796476;3325.1354647791504;3336.332733453319;3339.282054358325;3350.212700015494;1073.1200415659084;1100.4662738571785;1110.0564520157802;1114.7714718482773;1129.1649730844356;10944.4527773611;10958.616448424347;10961.287516311415;10978.286411383235;10979.28634638746;4530.977345113253;4540.886477838015;4588.7935042923145;4594.19601569724;4598.724076555417;9449.858252126223;9453.149216570542;9469.526523673789;9474.668386606436;9476.384035037701;4961.578265847371;4980.950190498049;4993.725345106054;5000.599952003831;5001.074801161724;4461.843835465743;4501.099780044005;4503.189425903369;4504.121696269196;4511.436070491189;3491.5810102787827;3924.6271604197555;3978.76127432355;4004.419359192959;4012.3981402789814;1205.626255860686;1265.61398773375;1270.3775150176532;1274.1272228523492;1276.6084967385593;1224.1186345830988;1346.5287149497794;1434.1395162902384;1447.3414596358632;1484.1836989655862;7522.691347960517;7578.166401695809;7633.2366763323225;7663.195364486649;7664.846703066019;1192.648168790209;1195.1096433157354;1210.074293165729;1216.1486959128645;1225.7364666596545;6197.411245931629;6355.841103972345;6357.554971151975;6359.554831161867;6361.491080713532;9261.073892610684;9511.334206605472;9594.088561586777;9600.855987160197;9693.22454203662;9515.143637072697;9535.141837234682;9536.713898583053;9539.952300238594;9540.809183816285;7462.403007759366;7646.464747467624;7672.5012874163;7679.500832445873;7681.462297639112;7759.573223472763;7780.688772449039;7817.452778305464;7844.764657060297;7874.645640946057;7700.922990770021;8877.245434137913;8891.377603567948;8892.59983300766;8908.955455222682;8827.646894124164;8829.2495137914;8965.103489651243;8967.596458159269;9090.09099090112;7103.82240443993;7206.171290301682;7208.855822883618;7215.60314182725;7216.3505284524645;6870.587764734132;7344.265573442613;7765.961170194113;7790.376769858511;7802.141764406025;7488.625568721651;7523.247675232433;7525.623718814147;7596.582187979493;7643.656035478528;7377.8155546111775;7422.666014943082;7431.071116110486;7516;7532.924670753441;7510.248975102446;7536.359409450258;7588.886166707393;7629.46593738433;7634.656440460304;7009.474289428415;7038.507304488637;7058.611776352144;7061.6469176542;7062.223155452999;6972.267911869383;6974.546654467444;6998.580085194904;7014.859702806018;7016.298370162943 15:02:57 INFO - 2015-12-21 15:02:57,897 DEBUG : BROWSER_OUTPUT: |4;prototype.html;370.2410059378277;373.9214764899371;374.39348255825564;377.6619925166974;383.41529168018775;5853.648781073139;5946.167536544887;5950.523958083354;5972.7312270947805;5976.4919981801595;5744.368119506851;5838.357780644125;5847.064469684851;5850.444207800263;5864.472197502235;4588.426446694163;4599.701019433726;4604.125216208927;4604.97697511512;4613.723176609397;4494.325851122325;4553;4614.469336026366;4623.768811559426;4697.67116301859;4644.187267228239;4656.650751193657;4661.09108723799;4672;4676.275177347517;4607.14767767964;4607.378003969471;4607.631389488833;4646.512116227781;4650.325702773112;4108.198901214264;4114.423980642712;4119.814608342632;4122.093139509303;4153.73000754953;5588.804391846266;5681.403452637455;5683.687397193153;5686.545076393879;5732.140178973145;5467.20725494801;5471.288732464794;5471.835844924659;5473.452654734495;5473.972630136864;5979.282486101695;6327.14583531222;6332.4934005279465;6339.334369891188;6342.682865856688;4490.910181796378;4563.178627846986;4626.236670949289;4629.259318509023;4630.652701047401;6373.649449280288;6467.353264673542;6467.741290348334;6473.223213214397;6474.643894585796;4733.408323959505;4742.715437073786;4746.8101275948575;4748.548887855648;4748.6675932685075;4737.431508219036;4800.231962885921;4807.61539076873;4838.371011768448;4878.707277563358;5478.233047373371;5479.287692599936;5484.2596249506605;5485.835424937259;5486.341639003265;6383.170187875547;6389.488840892717;6392.680365981775;6394.744210231632;6397.488200943913;1726.7496213049037;1750.5711100780136;1751.0456801043463;1751.7635119258991;1763.7442570827416;1183.3136780667191;1213.5145941623246;1223.5167108992077;1234.5061975209808;1250.1811313589612;6063.605865618721;6073.969630151821;6131.356207598227;6160.353162917919;6161.537884658579;6539.444147247442;6542.705578248895;6549.705263263165;6550.672466376662;6557.540972132;6140.6929653517145;6148.538859585549;6149.538784591174;6152.200213972155;6154.5076393888385;3549.769264997767;3574.3566158091794;3595.6044835068124;3617.7105831533413;3633.2006958469096;6808.7276508939085;6815.148106486689;6817.863642727217;6825.48808839339;6835;6798.320167983162;6800.4219641330055;6803.319668033256;6811;6814;6701.5979041256705;6756.121704178524;6784.423324017415;6785.287544807823;6809.148856392951;6330.968345158245;6424.935750642434;6427.55007149495;6603.504737144829;6607.405333520006;6424.646644434601;6435.581687190318;6447.290798012309;6453.548251622341;6457;6660.467162627075;6684.398404143456;6689.866202676017;6691.5650482718465;6693.765718199839;4548.431446069241;4570.702904311209;4633.443986721615;4640.559146881041;4650.139724151043;6460;6472.579282346633;6478.1578394808375;6545.8690826184165;6546.378094081055;6401.263854656774;6404.455621272244;6417.903731443938;6419.2296924369375;6427.421532062137;6299.559030867796;6401.583897046677;6404.615723056631;6425.228972523327;6425.485961123192;6322.557420980487;6326.114343991937;6326.493880489642;6329.272133704499;6332.271788744353;6439.871202576016;6444.420002199825;6454.386833250834;6459.709313081016;6460.353964603502;6220.720067596879;6303.684815759102;6308.306086330408;6313;6325.240971083315 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: |5;yui.html;754.6264599023484;756.6746299091388;820.2576668115357;854.9786255343615;933.2953620016884;14676.853231467681;14724.19016954067;14756.557303280892;14881.25593720315;14896.627584310398;13781.17312961223;13870.306484675777;13874.167549947011;13886;13901.930490347559;773.6866569039548;779.9337056350217;916.5188276155005;919.7792529792835;931.0131260863507;870.5777697816554;921.2399770189587;924.9537523123851;930.6277489004385;931.613380447117;817.7873752824279;978.227200511595;983.4738414948005;984.5126662302171;984.8867380251255;822.5681517203486;951.3863558005082;965.6716716316416;967.2068903499134;970.0929630795229;717.3615482220828;717.4511498703496;718.270954980695;718.3678363040491;719.0400814912099;13726.176429414167;13768.724625507533;13775.173489590657;13815.861841381558;13862.65343366424;11984.460699268553;12415.689607759788;12531;12578.245305281713;13060.77568972409;9935.354201976921;9956.203503719686;10093.091621754003;10156.542955567018;10158.949205253926;3253.7722359434847;3285.5728755261903;3289.0954987378664;3296.983515082409;3306;54102.18846717302;54102.45897541013;54264.45735542633;54418.45581544213;55879.72060139673;10055.648052318134;10059.144972677404;10077.68213310928;10150.847737283946;10212.642557510451;18945.81054189468;18965;18985.715214271797;18992.05039748007;19001.61996760057;7612.429067819936;7694.648871148809;7751.496152750054;7764.6894124234395;7862.724804631925;4315.28797748368;4441.067375851043;4498.617617502549;4541.772911354486;4616.60758835496;3365.3101114271517;3382.661733826647;3396.1849156202334;3396.388650043016;3404.9659503405146;468.5197672385764;480.56508859482227;490.2474701333436;545.5662748115258;563.1946316767062;545.3837164004649;616.6639181646013;637.2671427857907;680.1532092544714;684.1345697692423;8757.518336491554;8967.10328967098;9032.412893161923;9145.81708365829;9167.541622918827;612.9938700613026;616.2543322579639;622.0606883605788;629.1600713048041;632.1466020871878;3930.665893399093;4136.31750761127;4332.220200363901;4367.694261401672;4401.669874759342;68081;68641.31358686449;68714;68754.65622671855;68832.31167688259;55921;56480.717596411756;56572.43427565671;56851.43148568544;58070.7096464515;55299.170512442346;55815.16277255845;55922.161167581704;56035.719821400635;56170.71914640482;8717.433366831136;8739.606717697592;8848.424852384574;9128.315376346667;9137.862932055892;39391;39799.80100099481;40150.79924600358;40753.59246407498;40941.97645058778;50754.49245507498;51094.48905510897;51276.74361628168;51680;53913;8203.384746144182;8235.276482844365;8390.580470976549;8407.663693452194;8563.314934805076;11931.522739090338;12178.756424871275;12280;13181;13420.865791341961;11027.503762330554;11651.708707282385;11694;11845.348505831917;12232.510699572273;11320.660380188607;11599.594014209462;11660.766784664089;11743.060555155396;12134.029277657943;11037.724056898642;11213.66359009231;11424.885751142383;11456.94271528637;11847.229930054864;10450.111740502143;10475.476226188508;10524.473776311;10525.684229473129;10581.947090264499;9841.507924603884;10008;10109.747256318651;10201.489925503547;10278.074973252444 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,811 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: __startTimestamp1450738977889__endTimestamp 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:02:57 INFO - 2015-12-21 15:02:57,898 DEBUG : BROWSER_OUTPUT: Number of tests: 6 15:02:57 INFO - 2015-12-21 15:02:57,899 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,899 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5597.78 Median:5811.65 stddev:619.34 (10.7%) stddev-sans-first:595.17 15:02:57 INFO - 2015-12-21 15:02:57,899 DEBUG : BROWSER_OUTPUT: Values: 3331.5 3391.5 3410.9 3416.7 3438.7 5585.3 5588.7 5599.4 5615.5 5619.9 5568.9 5644.4 5646.1 5662.2 5673.5 4936.9 5071.2 5099.4 5161.9 5206.7 5157.2 5164.1 5169.1 5170.2 5173.0 4985.6 5070.9 5088.2 5109.5 5162.7 5082.8 5206.5 5212.7 5214.2 5220.8 5144.3 5176.0 5201.8 5229.2 5252.1 5220.9 5260.7 5325.4 5391.1 5472.9 6059.7 6076.8 6082.4 6094.2 6096.8 6109.7 6118.4 6128.7 6131.4 6133.8 5494.1 5544.3 5613.8 5618.3 5644.9 6168.5 6195.3 6203.3 6221.0 6222.2 5778.7 6045.4 6056.4 6066.5 6066.9 5945.6 5979.6 6071.2 6078.0 6101.5 5946.5 5980.7 5984.1 5984.5 5987.9 5870.7 5979.6 5980.1 5983.0 6032.7 4601.0 4625.3 4626.3 4626.5 4632.5 4050.5 4138.1 4144.3 4161.1 4161.7 5677.9 5734.5 5736.9 5738.5 5743.7 6015.3 6039.5 6062.1 6063.4 6066.8 5539.4 5617.7 5645.7 5727.7 5759.6 5205.1 5215.7 5224.3 5224.6 5228.4 6001.3 6197.7 6205.5 6208.3 6216.4 5966.5 6002.4 6097.6 6099.1 6124.9 6185.9 6235.9 6240.5 6242.9 6243.4 5714.9 5822.2 6004.0 6019.2 6022.3 5841.2 5933.1 5940.1 5947.2 5968.6 5878.7 5881.3 5947.5 5949.5 5956.3 5735.9 5811.7 5930.6 5940.9 5947.6 5801.4 5871.0 5872.7 5880.0 5883.1 5939.5 5967.3 5972.4 5979.9 5990.9 5542.9 5637.5 5712.9 5825.8 5852.7 15:02:57 INFO - 2015-12-21 15:02:57,899 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,899 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54561.45 Median:182291.41 stddev:95162.13 (52.2%) stddev-sans-first:95342.70 15:02:57 INFO - 2015-12-21 15:02:57,900 DEBUG : BROWSER_OUTPUT: Values: 560.0 611.9 620.5 632.9 648.0 41701.4 42270.4 42358.2 42413.0 42429.8 45983.0 46599.3 46605.8 46652.3 46685.3 8212.3 8320.9 9331.6 9484.3 10803.4 11093.7 11165.9 11186.4 11204.7 11217.8 11070.9 11201.7 11211.4 11215.8 11226.3 11077.4 11089.1 11172.3 11188.5 11191.1 10960.9 11016.8 11084.1 11098.9 11104.8 44895.1 45246.8 45277.0 45370.5 45473.8 3562.4 3576.7 3601.8 3665.6 3737.5 33178.0 33579.7 33703.7 33721.8 34001.0 3954.0 4039.3 4042.2 4115.4 4152.7 318864.0 319596.0 320988.0 321383.0 321407.0 59726.4 60657.4 65769.3 71045.6 72273.3 66997.0 67044.0 67188.7 67411.7 67478.3 18731.0 18823.6 18921.4 18950.7 19007.8 13022.9 13038.4 13052.4 13078.5 13199.3 20554.2 20683.5 20729.9 20769.7 20776.8 8081.1 8087.4 8239.5 8267.1 8273.4 1020.5 1049.6 1071.5 1074.1 1168.7 29923.3 30054.8 30324.1 30408.1 30712.8 1120.8 1144.7 1164.7 1203.5 1277.0 13995.5 15873.1 15975.9 16117.8 16704.7 400037.0 401185.0 401298.0 402998.0 403665.0 57095.7 57407.4 57593.4 57613.0 57746.7 1393.2 1467.9 1494.1 1495.0 1508.9 24432.5 30850.2 31121.2 31355.2 31912.7 54206.2 54840.7 54902.7 54943.7 54973.0 359808.0 360981.0 361967.0 362990.0 363335.0 35613.5 35625.0 36188.0 36197.6 36542.5 47060.0 47929.3 48337.8 48660.0 49064.5 39818.0 40904.6 41217.0 41744.4 41866.4 39073.8 40437.0 40808.4 41428.8 41589.8 36354.5 36966.6 37118.1 37256.0 37432.3 32483.0 34816.2 35515.5 35837.5 36474.6 25808.6 26369.7 26494.2 26545.7 26763.7 15:02:57 INFO - 2015-12-21 15:02:57,900 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,900 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:34564.55 Median:2402.17 stddev:111059.81 (4623.3%) stddev-sans-first:111341.70 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: Values: 381.1 385.2 386.5 386.7 387.7 18260.9 19803.4 20264.6 20330.9 20341.8 17289.0 17342.6 17824.0 18023.5 19635.8 5711.7 5859.5 5864.7 5872.0 5872.5 5635.1 5649.2 5690.6 5818.1 5843.3 4332.6 4460.0 5611.4 5616.1 6073.3 6133.3 6169.7 6185.8 6199.7 6213.5 5323.2 5345.0 5352.4 5356.4 5357.2 21787.0 21873.7 22012.0 22311.1 22432.4 2243.6 2355.9 2448.4 2476.5 2577.2 14049.8 14495.6 14549.3 14659.7 15056.2 1848.3 1961.5 1971.1 2001.6 2052.8 66592.0 67318.3 68188.0 68532.3 69232.7 9904.8 10001.3 10162.8 10175.7 10231.8 12934.6 12938.5 12944.0 13016.9 13063.0 8302.7 8457.2 8470.0 8505.2 8516.4 8862.9 8903.6 8924.4 8938.4 8942.0 6348.0 6372.3 6381.3 6412.8 6436.0 1669.4 1711.3 1759.9 1790.7 1836.9 1005.2 1043.9 1058.9 1143.8 1165.7 12593.9 12975.7 13112.8 13317.0 13325.9 501.9 533.8 546.0 554.6 557.9 5155.3 6822.1 6940.9 6952.8 7034.1 669535.0 673353.0 677096.0 684546.0 696019.0 86780.0 87808.0 88875.0 89499.0 91257.0 604.6 606.2 609.6 610.7 612.3 12023.6 12409.1 12410.2 12463.3 12478.9 40868.6 41054.8 41916.2 42139.0 42272.2 55522.0 56539.0 57078.0 57397.1 58266.7 15547.7 15631.4 15921.7 16120.2 16171.5 19545.0 19867.4 19882.3 20251.2 20336.7 16683.2 16817.7 17012.8 17324.0 17324.0 16435.2 16561.1 16723.7 17044.1 17145.6 13673.8 15033.6 17271.2 17797.6 17844.6 15589.2 15663.3 15687.2 15880.2 16077.4 16220.1 16379.5 16394.3 17013.1 17223.0 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5718.91 Median:6921.43 stddev:2899.12 (41.9%) stddev-sans-first:2890.40 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: Values: 1560.4 1567.9 1786.6 1823.1 1847.9 7260.6 7446.0 7920.4 8265.3 8288.8 7439.7 7532.2 7590.2 7608.0 7618.6 2361.5 2396.3 2397.7 2398.4 2399.3 2110.7 2155.9 2349.8 2380.1 2396.8 2280.7 2290.7 2293.3 2294.6 2296.2 2352.4 2355.3 2355.8 2362.3 2365.0 2312.9 2358.8 2359.2 2362.1 2364.5 8018.7 8076.9 8082.4 8093.0 8096.3 4937.7 5010.8 5012.5 5017.0 5017.3 3276.8 3325.1 3336.3 3339.3 3350.2 1073.1 1100.5 1110.1 1114.8 1129.2 10944.5 10958.6 10961.3 10978.3 10979.3 4531.0 4540.9 4588.8 4594.2 4598.7 9449.9 9453.1 9469.5 9474.7 9476.4 4961.6 4981.0 4993.7 5000.6 5001.1 4461.8 4501.1 4503.2 4504.1 4511.4 3491.6 3924.6 3978.8 4004.4 4012.4 1205.6 1265.6 1270.4 1274.1 1276.6 1224.1 1346.5 1434.1 1447.3 1484.2 7522.7 7578.2 7633.2 7663.2 7664.8 1192.6 1195.1 1210.1 1216.1 1225.7 6197.4 6355.8 6357.6 6359.6 6361.5 9261.1 9511.3 9594.1 9600.9 9693.2 9515.1 9535.1 9536.7 9540.0 9540.8 7462.4 7646.5 7672.5 7679.5 7681.5 7759.6 7780.7 7817.5 7844.8 7874.6 7700.9 8877.2 8891.4 8892.6 8909.0 8827.6 8829.2 8965.1 8967.6 9090.1 7103.8 7206.2 7208.9 7215.6 7216.4 6870.6 7344.3 7766.0 7790.4 7802.1 7488.6 7523.2 7525.6 7596.6 7643.7 7377.8 7422.7 7431.1 7516.0 7532.9 7510.2 7536.4 7588.9 7629.5 7634.7 7009.5 7038.5 7058.6 7061.6 7062.2 6972.3 6974.5 6998.6 7014.9 7016.3 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5361.12 Median:6021.44 stddev:1558.63 (25.9%) stddev-sans-first:1517.32 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: Values: 370.2 373.9 374.4 377.7 383.4 5853.6 5946.2 5950.5 5972.7 5976.5 5744.4 5838.4 5847.1 5850.4 5864.5 4588.4 4599.7 4604.1 4605.0 4613.7 4494.3 4553.0 4614.5 4623.8 4697.7 4644.2 4656.7 4661.1 4672.0 4676.3 4607.1 4607.4 4607.6 4646.5 4650.3 4108.2 4114.4 4119.8 4122.1 4153.7 5588.8 5681.4 5683.7 5686.5 5732.1 5467.2 5471.3 5471.8 5473.5 5474.0 5979.3 6327.1 6332.5 6339.3 6342.7 4490.9 4563.2 4626.2 4629.3 4630.7 6373.6 6467.4 6467.7 6473.2 6474.6 4733.4 4742.7 4746.8 4748.5 4748.7 4737.4 4800.2 4807.6 4838.4 4878.7 5478.2 5479.3 5484.3 5485.8 5486.3 6383.2 6389.5 6392.7 6394.7 6397.5 1726.7 1750.6 1751.0 1751.8 1763.7 1183.3 1213.5 1223.5 1234.5 1250.2 6063.6 6074.0 6131.4 6160.4 6161.5 6539.4 6542.7 6549.7 6550.7 6557.5 6140.7 6148.5 6149.5 6152.2 6154.5 3549.8 3574.4 3595.6 3617.7 3633.2 6808.7 6815.1 6817.9 6825.5 6835.0 6798.3 6800.4 6803.3 6811.0 6814.0 6701.6 6756.1 6784.4 6785.3 6809.1 6331.0 6424.9 6427.6 6603.5 6607.4 6424.6 6435.6 6447.3 6453.5 6457.0 6660.5 6684.4 6689.9 6691.6 6693.8 4548.4 4570.7 4633.4 4640.6 4650.1 6460.0 6472.6 6478.2 6545.9 6546.4 6401.3 6404.5 6417.9 6419.2 6427.4 6299.6 6401.6 6404.6 6425.2 6425.5 6322.6 6326.1 6326.5 6329.3 6332.3 6439.9 6444.4 6454.4 6459.7 6460.4 6220.7 6303.7 6308.3 6313.0 6325.2 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: 15:02:57 INFO - 2015-12-21 15:02:57,902 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15172.36 Median:50924.49 stddev:18676.84 (36.7%) stddev-sans-first:18697.86 15:02:57 INFO - 2015-12-21 15:02:57,903 DEBUG : BROWSER_OUTPUT: Values: 754.6 756.7 820.3 855.0 933.3 14676.9 14724.2 14756.6 14881.3 14896.6 13781.2 13870.3 13874.2 13886.0 13901.9 773.7 779.9 916.5 919.8 931.0 870.6 921.2 925.0 930.6 931.6 817.8 978.2 983.5 984.5 984.9 822.6 951.4 965.7 967.2 970.1 717.4 717.5 718.3 718.4 719.0 13726.2 13768.7 13775.2 13815.9 13862.7 11984.5 12415.7 12531.0 12578.2 13060.8 9935.4 9956.2 10093.1 10156.5 10158.9 3253.8 3285.6 3289.1 3297.0 3306.0 54102.2 54102.5 54264.5 54418.5 55879.7 10055.6 10059.1 10077.7 10150.8 10212.6 18945.8 18965.0 18985.7 18992.1 19001.6 7612.4 7694.6 7751.5 7764.7 7862.7 4315.3 4441.1 4498.6 4541.8 4616.6 3365.3 3382.7 3396.2 3396.4 3405.0 468.5 480.6 490.2 545.6 563.2 545.4 616.7 637.3 680.2 684.1 8757.5 8967.1 9032.4 9145.8 9167.5 613.0 616.3 622.1 629.2 632.1 3930.7 4136.3 4332.2 4367.7 4401.7 68081.0 68641.3 68714.0 68754.7 68832.3 55921.0 56480.7 56572.4 56851.4 58070.7 55299.2 55815.2 55922.2 56035.7 56170.7 8717.4 8739.6 8848.4 9128.3 9137.9 39391.0 39799.8 40150.8 40753.6 40942.0 50754.5 51094.5 51276.7 51680.0 53913.0 8203.4 8235.3 8390.6 8407.7 8563.3 11931.5 12178.8 12280.0 13181.0 13420.9 11027.5 11651.7 11694.0 11845.3 12232.5 11320.7 11599.6 11660.8 11743.1 12134.0 11037.7 11213.7 11424.9 11456.9 11847.2 10450.1 10475.5 10524.5 10525.7 10581.9 9841.5 10008.0 10109.7 10201.5 10278.1 15:02:57 INFO - 2015-12-21 15:02:57,904 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:02:57 INFO - 2015-12-21 15:02:57,904 DEBUG : BROWSER_OUTPUT: 15:02:58 INFO - 2015-12-21 15:02:58,377 INFO : Browser exited with error code: 0 15:02:58 INFO - 2015-12-21 15:02:58,392 INFO : Completed test dromaeo_css (00:18:29) 15:02:58 INFO - 2015-12-21 15:02:58,392 INFO : Starting test kraken 15:02:58 INFO - 2015-12-21 15:02:58,392 DEBUG : operating with platform_type : linux_ 15:02:58 INFO - 2015-12-21 15:02:58,393 INFO : Initialising browser for kraken test... 15:02:58 INFO - 2015-12-21 15:02:58,399 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpAgSvbN/profile http://localhost:52214/getInfo.html 15:03:02 INFO - 2015-12-21 15:03:02,059 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:03:02 INFO - 2015-12-21 15:03:02,059 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:03:02 INFO - 2015-12-21 15:03:02,059 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:03:02 INFO - 2015-12-21 15:03:02,059 DEBUG : BROWSER_OUTPUT: __metrics 15:03:02 INFO - 2015-12-21 15:03:02,197 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:03:03 INFO - 2015-12-21 15:03:03,368 INFO : Browser initialized. 15:03:03 INFO - 2015-12-21 15:03:03,369 INFO : Running cycle 1/1 for kraken test... 15:03:03 INFO - 2015-12-21 15:03:03,369 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpAgSvbN/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:03:14 INFO - 2015-12-21 15:03:14,921 DEBUG : BROWSER_OUTPUT: RSS: Main: 129544192 15:03:14 INFO - 2015-12-21 15:03:14,921 DEBUG : BROWSER_OUTPUT: 15:03:17 INFO - 2015-12-21 15:03:17,341 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52214/tests/kraken/driver.html?testName=audio-beat-detection) 15:03:17 INFO - 2015-12-21 15:03:17,923 DEBUG : BROWSER_OUTPUT: RSS: Main: 164192256 15:03:17 INFO - 2015-12-21 15:03:17,924 DEBUG : BROWSER_OUTPUT: 15:03:20 INFO - 2015-12-21 15:03:20,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52214/tests/kraken/driver.html?testName=audio-dft) 15:03:21 INFO - 2015-12-21 15:03:21,067 DEBUG : BROWSER_OUTPUT: RSS: Main: 160546816 15:03:21 INFO - 2015-12-21 15:03:21,068 DEBUG : BROWSER_OUTPUT: 15:03:23 INFO - 2015-12-21 15:03:23,839 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52214/tests/kraken/driver.html?testName=audio-fft) 15:03:24 INFO - 2015-12-21 15:03:24,400 DEBUG : BROWSER_OUTPUT: RSS: Main: 158060544 15:03:24 INFO - 2015-12-21 15:03:24,400 DEBUG : BROWSER_OUTPUT: 15:03:26 INFO - 2015-12-21 15:03:26,356 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52214/tests/kraken/driver.html?testName=audio-oscillator) 15:03:26 INFO - 2015-12-21 15:03:26,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 158027776 15:03:26 INFO - 2015-12-21 15:03:26,923 DEBUG : BROWSER_OUTPUT: 15:03:29 INFO - 2015-12-21 15:03:29,056 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52214/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:03:29 INFO - 2015-12-21 15:03:29,617 DEBUG : BROWSER_OUTPUT: RSS: Main: 155877376 15:03:29 INFO - 2015-12-21 15:03:29,617 DEBUG : BROWSER_OUTPUT: 15:03:32 INFO - 2015-12-21 15:03:32,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52214/tests/kraken/driver.html?testName=imaging-darkroom) 15:03:33 INFO - 2015-12-21 15:03:33,021 DEBUG : BROWSER_OUTPUT: RSS: Main: 154841088 15:03:33 INFO - 2015-12-21 15:03:33,021 DEBUG : BROWSER_OUTPUT: 15:03:36 INFO - 2015-12-21 15:03:36,372 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52214/tests/kraken/driver.html?testName=imaging-desaturate) 15:03:36 INFO - 2015-12-21 15:03:36,933 DEBUG : BROWSER_OUTPUT: RSS: Main: 156078080 15:03:36 INFO - 2015-12-21 15:03:36,934 DEBUG : BROWSER_OUTPUT: 15:03:39 INFO - 2015-12-21 15:03:39,604 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52214/tests/kraken/driver.html?testName=json-parse-financial) 15:03:40 INFO - 2015-12-21 15:03:40,177 DEBUG : BROWSER_OUTPUT: RSS: Main: 157335552 15:03:40 INFO - 2015-12-21 15:03:40,177 DEBUG : BROWSER_OUTPUT: 15:03:41 INFO - 2015-12-21 15:03:41,677 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52214/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:03:42 INFO - 2015-12-21 15:03:42,230 DEBUG : BROWSER_OUTPUT: RSS: Main: 161026048 15:03:42 INFO - 2015-12-21 15:03:42,231 DEBUG : BROWSER_OUTPUT: 15:03:43 INFO - 2015-12-21 15:03:43,965 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52214/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:03:44 INFO - 2015-12-21 15:03:44,553 DEBUG : BROWSER_OUTPUT: RSS: Main: 159473664 15:03:44 INFO - 2015-12-21 15:03:44,553 DEBUG : BROWSER_OUTPUT: 15:03:46 INFO - 2015-12-21 15:03:46,509 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52214/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:03:47 INFO - 2015-12-21 15:03:47,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 165089280 15:03:47 INFO - 2015-12-21 15:03:47,076 DEBUG : BROWSER_OUTPUT: 15:03:49 INFO - 2015-12-21 15:03:49,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:03:49 INFO - 2015-12-21 15:03:49,930 DEBUG : BROWSER_OUTPUT: RSS: Main: 168923136 15:03:49 INFO - 2015-12-21 15:03:49,931 DEBUG : BROWSER_OUTPUT: 15:03:52 INFO - 2015-12-21 15:03:52,364 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:03:52 INFO - 2015-12-21 15:03:52,930 DEBUG : BROWSER_OUTPUT: RSS: Main: 166633472 15:03:52 INFO - 2015-12-21 15:03:52,931 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,519 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52214/tests/kraken/driver.html?testName=ai-astar) 15:03:54 INFO - 2015-12-21 15:03:54,769 DEBUG : BROWSER_OUTPUT: RSS: Main: 225894400 15:03:54 INFO - 2015-12-21 15:03:54,770 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,770 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:03:54 INFO - 2015-12-21 15:03:54,770 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:03:54 INFO - 2015-12-21 15:03:54,770 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:03:54 INFO - 2015-12-21 15:03:54,770 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:03:54 INFO - 2015-12-21 15:03:54,771 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;103;109;110;111;101;109;108;109 15:03:54 INFO - 2015-12-21 15:03:54,771 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;159;122;148;156;129;146;155;122;159;130 15:03:54 INFO - 2015-12-21 15:03:54,771 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;160;160;160;159;158;161;160;159 15:03:54 INFO - 2015-12-21 15:03:54,771 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;70;140;71;72;72;71;160;70 15:03:54 INFO - 2015-12-21 15:03:54,771 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;132;88;89;90;128;88;89;135 15:03:54 INFO - 2015-12-21 15:03:54,771 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;111;110;111;111;109;109 15:03:54 INFO - 2015-12-21 15:03:54,771 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;147;150;145;148;149;147;148;148;145 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;95;95;95;95;96 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;54;55;54;57;55;58;63;53;53 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;63;63;63;63;63;63;63;63;63 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;78;79;78;78;77;78;77;79;78 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;113;112;112;114;110;110;112;107;111;108 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;137;136;136;147;137;138;138;141;131 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;62;63;61;62;62;60;61;62;61;64 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,872 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: __startTimestamp1450739034768__endTimestamp 15:03:54 INFO - 2015-12-21 15:03:54,772 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: Number of tests: 14 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.90 Median:109.00 stddev:3.25 (3.0%) stddev-sans-first:3.42 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 103.0 109.0 110.0 111.0 101.0 109.0 108.0 109.0 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.60 Median:151.50 stddev:15.29 (10.1%) stddev-sans-first:15.02 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: Values: 159.0 122.0 148.0 156.0 129.0 146.0 155.0 122.0 159.0 130.0 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.60 Median:160.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 15:03:54 INFO - 2015-12-21 15:03:54,776 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 160.0 160.0 160.0 159.0 158.0 161.0 160.0 159.0 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.90 Median:71.00 stddev:33.60 (47.3%) stddev-sans-first:35.14 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 70.0 140.0 71.0 72.0 72.0 71.0 160.0 70.0 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:101.70 Median:89.00 stddev:20.75 (23.3%) stddev-sans-first:21.50 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 132.0 88.0 89.0 90.0 128.0 88.0 89.0 135.0 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.92 (0.8%) stddev-sans-first:0.93 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 111.0 110.0 111.0 111.0 109.0 109.0 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,777 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:1.58 (1.1%) stddev-sans-first:1.67 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: Values: 148.0 147.0 150.0 145.0 148.0 149.0 147.0 148.0 148.0 145.0 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.70 Median:55.00 stddev:3.02 (5.5%) stddev-sans-first:3.19 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: Values: 55.0 54.0 55.0 54.0 57.0 55.0 58.0 63.0 53.0 53.0 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:63.00 stddev:0.32 (0.5%) stddev-sans-first:0.00 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: Values: 62.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:78.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: Values: 77.0 78.0 79.0 78.0 78.0 77.0 78.0 77.0 79.0 78.0 15:03:54 INFO - 2015-12-21 15:03:54,778 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:110.90 Median:112.00 stddev:2.18 (1.9%) stddev-sans-first:2.18 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: Values: 113.0 112.0 112.0 114.0 110.0 110.0 112.0 107.0 111.0 108.0 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.90 Median:138.00 stddev:4.07 (2.9%) stddev-sans-first:4.31 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: Values: 138.0 137.0 136.0 136.0 147.0 137.0 138.0 138.0 141.0 131.0 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.14 (1.8%) stddev-sans-first:1.20 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: Values: 62.0 63.0 61.0 62.0 62.0 60.0 61.0 62.0 61.0 64.0 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:03:54 INFO - 2015-12-21 15:03:54,779 DEBUG : BROWSER_OUTPUT: 15:03:55 INFO - 2015-12-21 15:03:55,418 INFO : Browser exited with error code: 0 15:03:55 INFO - 2015-12-21 15:03:55,470 INFO : Completed test kraken (00:00:57) 15:03:55 INFO - 2015-12-21 15:03:55,470 INFO : Starting test v8_7 15:03:55 INFO - 2015-12-21 15:03:55,470 DEBUG : operating with platform_type : linux_ 15:03:55 INFO - 2015-12-21 15:03:55,471 INFO : Initialising browser for v8_7 test... 15:03:55 INFO - 2015-12-21 15:03:55,477 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpwS5fYF/profile http://localhost:52214/getInfo.html 15:03:59 INFO - 2015-12-21 15:03:59,215 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:03:59 INFO - 2015-12-21 15:03:59,216 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:03:59 INFO - 2015-12-21 15:03:59,216 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:03:59 INFO - 2015-12-21 15:03:59,216 DEBUG : BROWSER_OUTPUT: __metrics 15:03:59 INFO - 2015-12-21 15:03:59,344 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:04:00 INFO - 2015-12-21 15:04:00,616 INFO : Browser initialized. 15:04:00 INFO - 2015-12-21 15:04:00,617 INFO : Running cycle 1/1 for v8_7 test... 15:04:00 INFO - 2015-12-21 15:04:00,617 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpwS5fYF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:04:12 INFO - 2015-12-21 15:04:12,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 128634880 15:04:12 INFO - 2015-12-21 15:04:12,096 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,436 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52214/tests/v8_7/run.html (next: http://localhost:52214/tests/v8_7/run.html) 15:04:33 INFO - 2015-12-21 15:04:33,687 DEBUG : BROWSER_OUTPUT: RSS: Main: 422510592 15:04:33 INFO - 2015-12-21 15:04:33,687 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,687 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:04:33 INFO - 2015-12-21 15:04:33,687 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:04:33 INFO - 2015-12-21 15:04:33,687 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |0;Richards;162.97262059973923 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.67759003242875 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.2516056967328 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5000 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.073793755913 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |5;Earley;553.0973451327434 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |6;Boyer;10568.421052631578 15:04:33 INFO - 2015-12-21 15:04:33,688 DEBUG : BROWSER_OUTPUT: |7;RegExp;23976.190476190477 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: |8;Splay;524.3838489774515 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: __startTimestamp1450739073684__endTimestamp 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: Number of tests: 10 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.97 Median:162.97 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: Values: 163.0 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,689 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.68 Median:170.68 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,690 DEBUG : BROWSER_OUTPUT: Values: 170.7 15:04:33 INFO - 2015-12-21 15:04:33,690 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,690 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.25 Median:279.25 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,690 DEBUG : BROWSER_OUTPUT: Values: 279.3 15:04:33 INFO - 2015-12-21 15:04:33,690 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,690 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5000.00 Median:5000.00 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,690 DEBUG : BROWSER_OUTPUT: Values: 5000.0 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.07 Median:946.07 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: Values: 946.1 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.10 Median:553.10 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: Values: 553.1 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10568.42 Median:10568.42 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: Values: 10568.4 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23976.19 Median:23976.19 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: Values: 23976.2 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,691 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:524.38 Median:524.38 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,692 DEBUG : BROWSER_OUTPUT: Values: 524.4 15:04:33 INFO - 2015-12-21 15:04:33,692 DEBUG : BROWSER_OUTPUT: 15:04:33 INFO - 2015-12-21 15:04:33,692 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 15:04:33 INFO - 2015-12-21 15:04:33,692 DEBUG : BROWSER_OUTPUT: Values: 4830.9 15:04:33 INFO - 2015-12-21 15:04:33,692 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:04:33 INFO - 2015-12-21 15:04:33,692 DEBUG : BROWSER_OUTPUT: 15:04:34 INFO - 2015-12-21 15:04:34,308 INFO : Browser exited with error code: 0 15:04:34 INFO - 2015-12-21 15:04:34,325 INFO : Completed test v8_7 (00:00:38) 15:04:34 INFO - 2015-12-21 15:04:34,769 INFO : Completed test suite (00:20:06) 15:04:34 INFO - 2015-12-21 15:04:34,770 DEBUG : Working with test: dromaeo_css 15:04:34 INFO - 2015-12-21 15:04:34,770 DEBUG : Generating results file: dromaeo_css 15:04:34 INFO - 2015-12-21 15:04:34,771 DEBUG : Working with test: kraken 15:04:34 INFO - 2015-12-21 15:04:34,771 DEBUG : Generating results file: kraken 15:04:34 INFO - 2015-12-21 15:04:34,771 INFO : javascript benchmark 15:04:34 INFO - 2015-12-21 15:04:34,771 DEBUG : Working with test: v8_7 15:04:34 INFO - 2015-12-21 15:04:34,771 DEBUG : Generating results file: v8_7 15:04:34 INFO - 2015-12-21 15:04:34,772 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:04:34 INFO - 2015-12-21 15:04:34,824 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 15:04:34 INFO - 2015-12-21 15:04:34,824 DEBUG : process_Request line: dromaeo_css 7431.69 graph.html#tests=[[72,53,35]] 15:04:34 INFO - 2015-12-21 15:04:34,825 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:04:34 INFO - 2015-12-21 15:04:34,853 DEBUG : process_Request line: kraken 1458.30 graph.html#tests=[[232,53,35]] 15:04:34 INFO - 2015-12-21 15:04:34,853 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:04:34 INFO - 2015-12-21 15:04:34,883 DEBUG : process_Request line: v8_7 23324.68 graph.html#tests=[[230,53,35]] 15:04:34 INFO - 2015-12-21 15:04:34,885 INFO : javascript benchmark 15:04:34 INFO - 2015-12-21 15:04:34,888 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3331.500274958756, 3391.457366821306, 3410.880619178329, 3416.726661867049, 3438.724902007839, 5585.329760428749, 5588.748506317215, 5599.41206173351, 5615.522680572156, 5619.887602247953, 5568.914061757959, 5644.350899646542, 5646.096624540064, 5662.150677398392, 5673.461021202979, 4936.901261974758, 5071.163258062425, 5099.413567439737, 5161.870953226162, 5206.713630750326, 5157.226416037587, 5164.096283150452, 5169.09540830355, 5170.224466330062, 5173.017126745906, 4985.6260780441435, 5070.8732281693065, 5088.160453525183, 5109.489051094898, 5162.741862906859, 5082.796688132489, 5206.531412172903, 5212.661177023481, 5214.191800270964, 5220.791168353261, 5144.305518754958, 5176.042432150027, 5201.765920533585, 5229.189475631283, 5252.133397989327, 5220.947790522084, 5260.6580572263065, 5325.4142044375085, 5391.083515802323, 5472.945270547323, 6059.7273122709585, 6076.848078798022, 6082.361352058015, 6094.238220222473, 6096.786612468542, 6109.663968481732, 6118.449339559461, 6128.724207410677, 6131.417515336036, 6133.785317513865, 5494.066008778477, 5544.251526043973, 5613.831585052455, 5618.325800903917, 5644.887102257973, 6168.537359697951, 6195.256569211723, 6203.348648391944, 6221.0, 6222.191115155091, 5778.739956701958, 6045.3652366501765, 6056.424639659225, 6066.48434883031, 6066.908996365058, 5945.554083443759, 5979.641221526722, 6071.150038994544, 6077.997130473511, 6101.511879049665, 5946.494547963472, 5980.700964951735, 5984.102384642339, 5984.461398474158, 5987.880242395127, 5870.73581688825, 5979.611325263844, 5980.102984552352, 5983.04271316596, 6032.6983650818165, 4600.95399046012, 4625.259958406638, 4626.306054091913, 4626.467956185014, 4632.536746325408, 4050.5341885682933, 4138.13099249155, 4144.295469770146, 4161.0637606538285, 4161.667066634662, 5677.943220567824, 5734.5125664319, 5736.942630573724, 5738.483536481736, 5743.684097374685, 6015.30823955242, 6039.516838652898, 6062.090686396988, 6063.363346848606, 6066.787662431793, 5539.418361072029, 5617.719114044281, 5645.661260324394, 5727.713614319351, 5759.625624334405, 5205.089109405839, 5215.713135777569, 5224.268602395668, 5224.634275600747, 5228.398734145622, 6001.3098493672915, 6197.659128747872, 6205.472534834499, 6208.286047104551, 6216.409441103088, 5966.463018328372, 6002.429769171922, 6097.634141951497, 6099.146119543178, 6124.877502449837, 6185.907211391743, 6235.875282494416, 6240.469560087262, 6242.875142497034, 6243.43809057187, 5714.914276285942, 5822.243108395882, 6003.969980150071, 6019.187409699634, 6022.337542870368, 5841.18223448726, 5933.139694744152, 5940.079287710412, 5947.167396564571, 5968.582199246011, 5878.676672782867, 5881.264841894763, 5947.464728174485, 5949.52403807703, 5956.344802071855, 5735.8566035849435, 5811.651300921958, 5930.644161350333, 5940.851478712894, 5947.613405128653, 5801.36185019639, 5870.970645146917, 5872.677002764889, 5880.0, 5883.146943693226, 5939.524838013035, 5967.254093238345, 5972.402759724167, 5979.850503737267, 5990.88018239624, 5542.944570554243, 5637.46444087811, 5712.885742285214, 5825.79609713658, 5852.741660542833], "unit": "ms", "name": "dojo.html", "value": 5557.925053806838}, {"lowerIsBetter": false, "replicates": [559.974801133949, 611.8623309755304, 620.4601996263252, 632.9367063293669, 648.0182523476931, 41701.37447938283, 42270.36594451082, 42358.15283694324, 42413.0, 42429.78785106074, 45983.0, 46599.301010484785, 46605.766971165096, 46652.3002154968, 46685.29972050413, 8212.260896519312, 8320.875186872201, 9331.580078896432, 9484.288678349118, 10803.4058126803, 11093.667189984313, 11165.888341116566, 11186.44067796607, 11204.719882002933, 11217.831732523979, 11070.889291107067, 11201.66395008151, 11211.439428028607, 11215.831762523569, 11226.270292431007, 11077.390743509104, 11089.05743011841, 11172.329660220414, 11188.545489086408, 11191.104711623051, 10960.890391096067, 11016.834747478793, 11084.057855082328, 11098.944505277423, 11104.833427498594, 44895.10209795819, 45246.773766131286, 45277.0, 45370.546294536965, 45473.772631136635, 3562.3943929532043, 3576.678098971079, 3601.8199090045655, 3665.6151104133946, 3737.5328083989502, 33178.0, 33579.6642033579, 33703.6629633703, 33721.83139084289, 34001.0, 3953.9802300988313, 4039.252738243405, 4042.151148258869, 4115.403266526367, 4152.688548358885, 318864.0, 319596.0, 320988.0, 321383.0, 321407.0, 59726.402735972086, 60657.39342606517, 65769.34230657728, 71045.64477177581, 72273.30223535454, 66997.0, 67044.0, 67188.66405667941, 67411.66294168498, 67478.32521674721, 18731.0, 18823.623527529373, 18921.432357029313, 18950.715739263924, 19007.809921900604, 13022.869771302356, 13038.41327140281, 13052.412641431158, 13078.542251021168, 13199.274039927896, 20554.17783288652, 20683.4829129273, 20729.85985770767, 20769.688454673193, 20776.792232077787, 8081.111077781439, 8087.393445491612, 8239.505629662239, 8267.131951145164, 8273.420860539825, 1020.5152552537485, 1049.5801679328329, 1071.5392381276126, 1074.1460538871459, 1168.7370341673056, 29923.251918702208, 30054.84972575123, 30324.090277291718, 30408.087757367313, 30712.846435768126, 1120.7646394257297, 1144.7023773818867, 1164.6622479480855, 1203.518592562982, 1276.997556917815, 13995.51015714445, 15873.12697801632, 15975.920120399323, 16117.758233626504, 16704.742575197964, 400037.0, 401185.0, 401298.0, 402998.0, 403665.0, 57095.71452142796, 57407.425925741045, 57593.42406575881, 57613.0, 57746.71126644424, 1393.2058726525986, 1467.9045862018934, 1494.0587429919299, 1495.0207614012838, 1508.8868334874708, 24432.51134977326, 30850.228744280674, 31121.172168461722, 31355.21611959628, 31912.68087319097, 54206.18690719721, 54840.72579637076, 54902.725486372314, 54943.72528137494, 54973.0, 359808.0, 360981.0, 361967.0, 362990.0, 363335.0, 35613.46579801357, 35625.0, 36188.0, 36197.63802361942, 36542.45186322154, 47060.0, 47929.28106078342, 48337.75831120822, 48660.0, 49064.509354906, 39818.0, 40904.590954090076, 41217.0, 41744.37383439312, 41866.37200441935, 39073.804630976665, 40437.0, 40808.38787418132, 41428.79285603673, 41589.79205103955, 36354.45468317925, 36966.63033369632, 37118.072048199014, 37256.0, 37432.25135497329, 32483.0, 34816.20509953465, 35515.46726799152, 35837.46243806397, 36474.635253647124, 25808.61287080733, 26369.736302637495, 26494.205173844817, 26545.734542654325, 26763.732362676903], "unit": "ms", "name": "ext.html", "value": 20794.597972370473}, {"lowerIsBetter": false, "replicates": [381.1063057131027, 385.24684242306296, 386.5168539325843, 386.700307261872, 387.6917850309005, 18260.90869545652, 19803.405897823053, 20264.59470810583, 20330.89834550827, 20341.796582034192, 17289.04910229937, 17342.56643583911, 17824.019678917655, 18023.549411264725, 19635.803641963576, 5711.657300561958, 5859.531237501011, 5864.70676466175, 5872.0, 5872.530197584183, 5635.070213414781, 5649.209110724501, 5690.630109042899, 5818.1272809078555, 5843.2695913010875, 4332.610065094141, 4459.955400446002, 5611.382747897748, 5616.073347897592, 6073.301570319403, 6133.294671112834, 6169.660668663222, 6185.845353866144, 6199.65901875399, 6213.502919766408, 5323.201519772027, 5345.0, 5352.437994010651, 5356.4107948125675, 5357.223202635635, 21787.0, 21873.67189492159, 22012.0, 22311.107555697752, 22432.439189020242, 2243.562505311465, 2355.882205889716, 2448.4490989527394, 2476.5418397596504, 2577.1624222127884, 14049.78925316121, 14495.637609059753, 14549.345279462344, 14659.706805863823, 15056.247187640574, 1848.2606957217085, 1961.5390383259924, 1971.073595410155, 2001.579668269665, 2052.8049835265624, 66592.0, 67318.3268167317, 68188.0, 68532.31467685309, 69232.653836731, 9904.752381190528, 10001.2999090063, 10162.796744065077, 10175.7456063599, 10231.846522302172, 12934.611961640978, 12938.547150849676, 12944.0, 13016.869831301756, 13063.0, 8302.6678932842, 8457.238848503664, 8470.0, 8505.23452889243, 8516.403851730443, 8862.911370886337, 8903.643854245758, 8924.419912705653, 8938.4190027648, 8942.0, 6347.968260158762, 6372.330905254881, 6381.329960354189, 6412.839679008062, 6435.967820160869, 1669.3572974404942, 1711.2812618700175, 1759.9472015839544, 1790.7045337519164, 1836.8714190006838, 1005.1506477026901, 1043.888259004158, 1058.9258751887294, 1143.7941170589374, 1165.7260543772206, 12593.874061259454, 12975.675608109685, 13112.803307950388, 13317.0, 13325.933370333087, 501.8770401251717, 533.8024930775638, 545.9863503412367, 554.6256277013, 557.9218909352694, 5155.32980712505, 6822.14755062765, 6940.861182776316, 6952.826179345456, 7034.050403195605, 669535.0, 673353.0, 677096.0, 684546.0, 696019.0, 86780.0, 87808.0, 88875.0, 89499.0, 91257.0, 604.5707547641224, 606.1967892542399, 609.600711533946, 610.7312782375741, 612.3080918561997, 12023.63929082129, 12409.069319801232, 12410.193337433038, 12463.314517701614, 12478.875211248134, 40868.59131408648, 41054.79472602618, 41916.16167676568, 42139.0, 42272.15455690808, 55522.0, 56539.0, 57078.0, 57397.13904291522, 58266.7086664564, 15547.689046219239, 15631.374745010527, 15921.68156636884, 16120.193990300673, 16171.514854554382, 19545.0, 19867.410607151105, 19882.304119355755, 20251.18995240233, 20336.69494957547, 16683.16584170811, 16817.747733784247, 17012.829871701124, 17324.0, 17324.0, 16435.178241088135, 16561.08914009741, 16723.74914376261, 17044.14779261057, 17145.571360716083, 13673.794893076412, 15033.624159396102, 17271.222794974005, 17797.644047119244, 17844.55388615295, 15589.220538972779, 15663.295151717972, 15687.215639217766, 15880.2059897007, 16077.35690572364, 16220.107894065939, 16379.508614741577, 16394.26225819817, 17013.14934253307, 17222.96662200272], "unit": "ms", "name": "jquery.html", "value": 9553.898209567284}, {"lowerIsBetter": false, "replicates": [1560.4304428883454, 1567.9216039198038, 1786.624808790154, 1823.1340113446115, 1847.8635639081965, 7260.636968151584, 7446.0, 7920.445568810185, 8265.256126948567, 8288.792780180498, 7439.665215065335, 7532.246775322479, 7590.165081841007, 7608.0489938757655, 7618.619069046553, 2361.539499797536, 2396.3290278721984, 2397.7242617098996, 2398.352444839892, 2399.2682231919257, 2110.67284570891, 2155.8706477611395, 2349.7650234976536, 2380.071772008918, 2396.8202384821207, 2280.703508543895, 2290.6678531612906, 2293.289080385086, 2294.609916314222, 2296.2422400607843, 2352.447174913894, 2355.258093700496, 2355.8468699534646, 2362.314928670692, 2364.9763502365013, 2312.9190478333176, 2358.8466749661216, 2359.245041586693, 2362.0669835414915, 2364.491634298634, 8018.67925282988, 8076.8788468173025, 8082.43422960393, 8093.028836539594, 8096.3118134958595, 4937.728424936627, 5010.799568017275, 5012.498750124995, 5017.0, 5017.322661440695, 3276.803391796476, 3325.1354647791504, 3336.332733453319, 3339.282054358325, 3350.212700015494, 1073.1200415659084, 1100.4662738571785, 1110.0564520157802, 1114.7714718482773, 1129.1649730844356, 10944.4527773611, 10958.616448424347, 10961.287516311415, 10978.286411383235, 10979.28634638746, 4530.977345113253, 4540.886477838015, 4588.7935042923145, 4594.19601569724, 4598.724076555417, 9449.858252126223, 9453.149216570542, 9469.526523673789, 9474.668386606436, 9476.384035037701, 4961.578265847371, 4980.950190498049, 4993.725345106054, 5000.599952003831, 5001.074801161724, 4461.843835465743, 4501.099780044005, 4503.189425903369, 4504.121696269196, 4511.436070491189, 3491.5810102787827, 3924.6271604197555, 3978.76127432355, 4004.419359192959, 4012.3981402789814, 1205.626255860686, 1265.61398773375, 1270.3775150176532, 1274.1272228523492, 1276.6084967385593, 1224.1186345830988, 1346.5287149497794, 1434.1395162902384, 1447.3414596358632, 1484.1836989655862, 7522.691347960517, 7578.166401695809, 7633.2366763323225, 7663.195364486649, 7664.846703066019, 1192.648168790209, 1195.1096433157354, 1210.074293165729, 1216.1486959128645, 1225.7364666596545, 6197.411245931629, 6355.841103972345, 6357.554971151975, 6359.554831161867, 6361.491080713532, 9261.073892610684, 9511.334206605472, 9594.088561586777, 9600.855987160197, 9693.22454203662, 9515.143637072697, 9535.141837234682, 9536.713898583053, 9539.952300238594, 9540.809183816285, 7462.403007759366, 7646.464747467624, 7672.5012874163, 7679.500832445873, 7681.462297639112, 7759.573223472763, 7780.688772449039, 7817.452778305464, 7844.764657060297, 7874.645640946057, 7700.922990770021, 8877.245434137913, 8891.377603567948, 8892.59983300766, 8908.955455222682, 8827.646894124164, 8829.2495137914, 8965.103489651243, 8967.596458159269, 9090.09099090112, 7103.82240443993, 7206.171290301682, 7208.855822883618, 7215.60314182725, 7216.3505284524645, 6870.587764734132, 7344.265573442613, 7765.961170194113, 7790.376769858511, 7802.141764406025, 7488.625568721651, 7523.247675232433, 7525.623718814147, 7596.582187979493, 7643.656035478528, 7377.8155546111775, 7422.666014943082, 7431.071116110486, 7516.0, 7532.924670753441, 7510.248975102446, 7536.359409450258, 7588.886166707393, 7629.46593738433, 7634.656440460304, 7009.474289428415, 7038.507304488637, 7058.611776352144, 7061.6469176542, 7062.223155452999, 6972.267911869383, 6974.546654467444, 6998.580085194904, 7014.859702806018, 7016.298370162943], "unit": "ms", "name": "mootools.html", "value": 4737.734556752476}, {"lowerIsBetter": false, "replicates": [370.2410059378277, 373.9214764899371, 374.39348255825564, 377.6619925166974, 383.41529168018775, 5853.648781073139, 5946.167536544887, 5950.523958083354, 5972.7312270947805, 5976.4919981801595, 5744.368119506851, 5838.357780644125, 5847.064469684851, 5850.444207800263, 5864.472197502235, 4588.426446694163, 4599.701019433726, 4604.125216208927, 4604.97697511512, 4613.723176609397, 4494.325851122325, 4553.0, 4614.469336026366, 4623.768811559426, 4697.67116301859, 4644.187267228239, 4656.650751193657, 4661.09108723799, 4672.0, 4676.275177347517, 4607.14767767964, 4607.378003969471, 4607.631389488833, 4646.512116227781, 4650.325702773112, 4108.198901214264, 4114.423980642712, 4119.814608342632, 4122.093139509303, 4153.73000754953, 5588.804391846266, 5681.403452637455, 5683.687397193153, 5686.545076393879, 5732.140178973145, 5467.20725494801, 5471.288732464794, 5471.835844924659, 5473.452654734495, 5473.972630136864, 5979.282486101695, 6327.14583531222, 6332.4934005279465, 6339.334369891188, 6342.682865856688, 4490.910181796378, 4563.178627846986, 4626.236670949289, 4629.259318509023, 4630.652701047401, 6373.649449280288, 6467.353264673542, 6467.741290348334, 6473.223213214397, 6474.643894585796, 4733.408323959505, 4742.715437073786, 4746.8101275948575, 4748.548887855648, 4748.6675932685075, 4737.431508219036, 4800.231962885921, 4807.61539076873, 4838.371011768448, 4878.707277563358, 5478.233047373371, 5479.287692599936, 5484.2596249506605, 5485.835424937259, 5486.341639003265, 6383.170187875547, 6389.488840892717, 6392.680365981775, 6394.744210231632, 6397.488200943913, 1726.7496213049037, 1750.5711100780136, 1751.0456801043463, 1751.7635119258991, 1763.7442570827416, 1183.3136780667191, 1213.5145941623246, 1223.5167108992077, 1234.5061975209808, 1250.1811313589612, 6063.605865618721, 6073.969630151821, 6131.356207598227, 6160.353162917919, 6161.537884658579, 6539.444147247442, 6542.705578248895, 6549.705263263165, 6550.672466376662, 6557.540972132, 6140.6929653517145, 6148.538859585549, 6149.538784591174, 6152.200213972155, 6154.5076393888385, 3549.769264997767, 3574.3566158091794, 3595.6044835068124, 3617.7105831533413, 3633.2006958469096, 6808.7276508939085, 6815.148106486689, 6817.863642727217, 6825.48808839339, 6835.0, 6798.320167983162, 6800.4219641330055, 6803.319668033256, 6811.0, 6814.0, 6701.5979041256705, 6756.121704178524, 6784.423324017415, 6785.287544807823, 6809.148856392951, 6330.968345158245, 6424.935750642434, 6427.55007149495, 6603.504737144829, 6607.405333520006, 6424.646644434601, 6435.581687190318, 6447.290798012309, 6453.548251622341, 6457.0, 6660.467162627075, 6684.398404143456, 6689.866202676017, 6691.5650482718465, 6693.765718199839, 4548.431446069241, 4570.702904311209, 4633.443986721615, 4640.559146881041, 4650.139724151043, 6460.0, 6472.579282346633, 6478.1578394808375, 6545.8690826184165, 6546.378094081055, 6401.263854656774, 6404.455621272244, 6417.903731443938, 6419.2296924369375, 6427.421532062137, 6299.559030867796, 6401.583897046677, 6404.615723056631, 6425.228972523327, 6425.485961123192, 6322.557420980487, 6326.114343991937, 6326.493880489642, 6329.272133704499, 6332.271788744353, 6439.871202576016, 6444.420002199825, 6454.386833250834, 6459.709313081016, 6460.353964603502, 6220.720067596879, 6303.684815759102, 6308.306086330408, 6313.0, 6325.240971083315], "unit": "ms", "name": "prototype.html", "value": 4876.401460682974}, {"lowerIsBetter": false, "replicates": [754.6264599023484, 756.6746299091388, 820.2576668115357, 854.9786255343615, 933.2953620016884, 14676.853231467681, 14724.19016954067, 14756.557303280892, 14881.25593720315, 14896.627584310398, 13781.17312961223, 13870.306484675777, 13874.167549947011, 13886.0, 13901.930490347559, 773.6866569039548, 779.9337056350217, 916.5188276155005, 919.7792529792835, 931.0131260863507, 870.5777697816554, 921.2399770189587, 924.9537523123851, 930.6277489004385, 931.613380447117, 817.7873752824279, 978.227200511595, 983.4738414948005, 984.5126662302171, 984.8867380251255, 822.5681517203486, 951.3863558005082, 965.6716716316416, 967.2068903499134, 970.0929630795229, 717.3615482220828, 717.4511498703496, 718.270954980695, 718.3678363040491, 719.0400814912099, 13726.176429414167, 13768.724625507533, 13775.173489590657, 13815.861841381558, 13862.65343366424, 11984.460699268553, 12415.689607759788, 12531.0, 12578.245305281713, 13060.77568972409, 9935.354201976921, 9956.203503719686, 10093.091621754003, 10156.542955567018, 10158.949205253926, 3253.7722359434847, 3285.5728755261903, 3289.0954987378664, 3296.983515082409, 3306.0, 54102.18846717302, 54102.45897541013, 54264.45735542633, 54418.45581544213, 55879.72060139673, 10055.648052318134, 10059.144972677404, 10077.68213310928, 10150.847737283946, 10212.642557510451, 18945.81054189468, 18965.0, 18985.715214271797, 18992.05039748007, 19001.61996760057, 7612.429067819936, 7694.648871148809, 7751.496152750054, 7764.6894124234395, 7862.724804631925, 4315.28797748368, 4441.067375851043, 4498.617617502549, 4541.772911354486, 4616.60758835496, 3365.3101114271517, 3382.661733826647, 3396.1849156202334, 3396.388650043016, 3404.9659503405146, 468.5197672385764, 480.56508859482227, 490.2474701333436, 545.5662748115258, 563.1946316767062, 545.3837164004649, 616.6639181646013, 637.2671427857907, 680.1532092544714, 684.1345697692423, 8757.518336491554, 8967.10328967098, 9032.412893161923, 9145.81708365829, 9167.541622918827, 612.9938700613026, 616.2543322579639, 622.0606883605788, 629.1600713048041, 632.1466020871878, 3930.665893399093, 4136.31750761127, 4332.220200363901, 4367.694261401672, 4401.669874759342, 68081.0, 68641.31358686449, 68714.0, 68754.65622671855, 68832.31167688259, 55921.0, 56480.717596411756, 56572.43427565671, 56851.43148568544, 58070.7096464515, 55299.170512442346, 55815.16277255845, 55922.161167581704, 56035.719821400635, 56170.71914640482, 8717.433366831136, 8739.606717697592, 8848.424852384574, 9128.315376346667, 9137.862932055892, 39391.0, 39799.80100099481, 40150.79924600358, 40753.59246407498, 40941.97645058778, 50754.49245507498, 51094.48905510897, 51276.74361628168, 51680.0, 53913.0, 8203.384746144182, 8235.276482844365, 8390.580470976549, 8407.663693452194, 8563.314934805076, 11931.522739090338, 12178.756424871275, 12280.0, 13181.0, 13420.865791341961, 11027.503762330554, 11651.708707282385, 11694.0, 11845.348505831917, 12232.510699572273, 11320.660380188607, 11599.594014209462, 11660.766784664089, 11743.060555155396, 12134.029277657943, 11037.724056898642, 11213.66359009231, 11424.885751142383, 11456.94271528637, 11847.229930054864, 10450.111740502143, 10475.476226188508, 10524.473776311, 10525.684229473129, 10581.947090264499, 9841.507924603884, 10008.0, 10109.747256318651, 10201.489925503547, 10278.074973252444], "unit": "ms", "name": "yui.html", "value": 6603.454885344834}], "name": "dromaeo_css", "value": 7431.690119381119}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 103.0, 109.0, 110.0, 111.0, 101.0, 109.0, 108.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.9}, {"lowerIsBetter": true, "replicates": [159.0, 122.0, 148.0, 156.0, 129.0, 146.0, 155.0, 122.0, 159.0, 130.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.6}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 160.0, 160.0, 160.0, 159.0, 158.0, 161.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 159.6}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 70.0, 140.0, 71.0, 72.0, 72.0, 71.0, 160.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 86.9}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 132.0, 88.0, 89.0, 90.0, 128.0, 88.0, 89.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 101.7}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 111.0, 110.0, 111.0, 111.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [148.0, 147.0, 150.0, 145.0, 148.0, 149.0, 147.0, 148.0, 148.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.5}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [55.0, 54.0, 55.0, 54.0, 57.0, 55.0, 58.0, 63.0, 53.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.7}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.9}, {"lowerIsBetter": true, "replicates": [77.0, 78.0, 79.0, 78.0, 78.0, 77.0, 78.0, 77.0, 79.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.9}, {"lowerIsBetter": true, "replicates": [113.0, 112.0, 112.0, 114.0, 110.0, 110.0, 112.0, 107.0, 111.0, 108.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 110.9}, {"lowerIsBetter": true, "replicates": [138.0, 137.0, 136.0, 136.0, 147.0, 137.0, 138.0, 138.0, 141.0, 131.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.9}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 61.0, 62.0, 62.0, 60.0, 61.0, 62.0, 61.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.8}], "name": "kraken", "value": 1458.3000000000004}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.97262059973923], "unit": "score", "name": "Richards", "value": 216.61307199999993}, {"lowerIsBetter": false, "replicates": [170.67759003242875], "unit": "score", "name": "DeltaBlue", "value": 387.3853620000001}, {"lowerIsBetter": false, "replicates": [279.2516056967328], "unit": "score", "name": "Encrypt", "value": 953.1941609999996}, {"lowerIsBetter": false, "replicates": [5000.0], "unit": "score", "name": "Decrypt", "value": 53.23619999999997}, {"lowerIsBetter": false, "replicates": [946.073793755913], "unit": "score", "name": "RayTrace", "value": 782.1683729999996}, {"lowerIsBetter": false, "replicates": [553.0973451327434], "unit": "score", "name": "Earley", "value": 1204.9651040000003}, {"lowerIsBetter": false, "replicates": [10568.421052631578], "unit": "score", "name": "Boyer", "value": 63.06173804780874}, {"lowerIsBetter": false, "replicates": [23976.190476190477], "unit": "score", "name": "RegExp", "value": 37.995402184707075}, {"lowerIsBetter": false, "replicates": [524.3838489774515], "unit": "score", "name": "Splay", "value": 155.40333699999996}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23324.684940238894}]} 15:04:34 INFO - RETURN: dromaeo_css: 7431.69 15:04:34 INFO - RETURN: kraken: 1458.30 15:04:34 INFO - RETURN: v8_7: 23324.68 15:04:34 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23324.68"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1458.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7431.69"}}} 15:04:34 INFO - Return code: 0 15:04:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 15:04:35 INFO - # TBPL SUCCESS # 15:04:35 INFO - Running post-action listener: _resource_record_post_action 15:04:35 INFO - Running post-run listener: _resource_record_post_run 15:04:36 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 319488; Write bytes: 507953152; Read time: 80; Write time: 606556 15:04:36 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 246054912; Read time: 0; Write time: 440136 15:04:36 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 319488; Write bytes: 261898240; Read time: 80; Write time: 166420 15:04:36 INFO - Running post-run listener: _upload_blobber_files 15:04:36 INFO - Blob upload gear active. 15:04:36 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 15:04:36 INFO - Copying logs to upload dir... 15:04:36 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.294602 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2015-12-21 15:04:35.950947) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 15:04:35.955058) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009889 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450729289/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 15:04:36.006359) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 15:04:36.006760) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=752f8c75933e5bfb3b8f10bb000001ce-1450734859.452953-601170511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004619 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 15:04:36.056033) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 15:04:36.056388) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 15:04:36.056697) ========= ========= Total master_lag: 0.25 =========