Copied to clipboard

Flag this post as spam?

This post will be reported to the moderators as potential spam to be looked at


  • Sergio 73 posts 93 karma points
    Nov 15, 2011 @ 10:30
    Sergio
    0

    Performance problems in Windows Azure

    Hello,

    We have our Umbraco site hosted in Windows Azure.

    The same pages need less than 1 second to be rendered in the local development server and more than 6 seconds in Windows Azure Server, with exactly the same content and configuration.

    Looking at the traces, the main problem seems to be between the Begin Load and End Load methods (more than 5 seconds).

    This is the full trace in Windows Azure:

    aspx.page    Begin PreInit       
    umbracoInit    handling request    5,71782991509564E-05    0.000057
    request handler    current url '/lang_castellano/aeropuertos/servidumbres/tramitacion/d_1844_1975_10jul.aspx'    0,014221005614259    0.014164
    umbracoRequestHandler    Just before xPath query (True, '29913')    0,0143294341455517    0.000108
    umbracoRequestHandler    After xPath query    0,014379434859834    0.000050
    umbracoRequestHandler    Access checking started    0,0144859986381017    0.000107
    umbracoRequestHandler    Page not protected    0,0145480183427698    0.000062
    umbracoRequestHandler    Access checking ended    0,0145844829310337    0.000036
    umbracoInit    Done handling request    0,0146196403826648    0.000035
    umbracoPage    Element loaded: phtitulo    0,0151804953309746    0.000561
    umbracoPage    Element loaded: phdescripcion    0,015267833010319    0.000087
    umbracoPage    Element loaded: phdescripcionlarga    0,0153338693625268    0.000066
    umbracoPage    Element loaded: phdocumento    0,0154039523621316    0.000070
    umbracoPage    Element loaded: abrirdirectamente    0,0154706120447045    0.000067
    umbracoPage    Element loaded: fecha    0,0155401136185066    0.000070
    umbracoPage    Element loaded: displayname    0,0156116442302656    0.000072
    umbracoPage    Element loaded: isimportant    0,0156767139204099    0.000065
    umbracoPage    Pagedata loaded for d_1844_1975_10jul (ID: 29913)    0,0157223684649121    0.000046
    umbracoPage    Looking up skin information    0,0158167746820253    0.000094
    default.aspx    Culture changed to es-ES    0,016255957352584    0.000439
    aspx.page    End PreInit    0,0166816291350994    0,000426
    aspx.page    Begin Init    0,0168115232784606    0,000130
    renderMacro    Rendering started (macro: Cabecera, type: 3, cacheRate: 0)    0,0253905967114442    0,008579
    umbracoMacro    Usercontrol added (/usercontrols/Cabecera.ascx)    0,0260957381155328    0,000705
    loadUserControl    Usercontrol added with id 'Cabecera_1'    0,0265701758563054    0,000474
    renderMacro    Rendering started (macro: MenuDG, type: 3, cacheRate: 0)    0,0350084966262065    0,008438
    umbracoMacro    Usercontrol added (usercontrols/MenuDG.ascx)    0,0351046104542359    0,000096
    loadUserControl    Usercontrol added with id 'MenuDG_2'    0,0355258508292818    0,000421
    renderMacro    Rendering started (macro: BannerIzquierdo, type: 3, cacheRate: 0)    0,044296679063433    0,008771
    umbracoMacro    Usercontrol added (usercontrols/BannerIzquierdo.ascx)    0,0443850672139657    0,000088
    loadUserControl    Usercontrol added with id 'BannerIzquierdo_3'    0,0448155742115514    0,000431
    renderMacro    Rendering started (macro: Rastro Migas, type: 1, cacheRate: 0)    0,0533690215760877    0,008553
    umbracoMacro    Before adding extensions    0,0535590073380603    0,000190
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltCommon, ExsltCommon    0,239015944209789    0,185457
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltDatesAndTimes, ExsltDatesAndTimes    0,239084290074809    0,000068
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltMath, ExsltMath    0,239105939019338    0,000022
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltRegularExpressions, ExsltRegularExpressions    0,239125543211699    0,000020
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltStrings, ExsltStrings    0,239143884076743    0,000018
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltSets, ExsltSets    0,23916645587402    0,000023
    umbracoXsltExtension    Extension added: urn:obtenerPropiedadTraducida, obtenerPropiedadTraducida    0,239187551011662    0,000021
    umbracoXsltExtension    Extension added: urn:ucomponents.urls, Urls    0,239207065204451    0,000020
    umbracoMacro    After adding extensions    0,239222339417431    0,000015
    umbracoMacro    Before performing transformation    0,239299112385179    0,000077
    umbracoMacro    After performing transformation    0,240042865033976    0,000744
    renderMacro    Rendering started (macro: CabeceraCanal, type: 3, cacheRate: 0)    0,25179841048376    0,011756
    umbracoMacro    Usercontrol added (usercontrols/CabeceraCanal.ascx)    0,251835366498255    0,000037
    loadUserControl    Usercontrol added with id 'CabeceraCanal_5'    0,252032266036828    0,000197
    renderMacro    Rendering started (macro: MenuHorizontal, type: 3, cacheRate: 0)    0,259719308479483    0,007687
    umbracoMacro    Usercontrol added (usercontrols/MenuHorizontal.ascx)    0,259757888771958    0,000039
    loadUserControl    Usercontrol added with id 'MenuHorizontal_6'    0,26011341088852    0,000356
    renderMacro    Rendering started (macro: [XSLT]TraduccionPropiedadesContenido, type: 1, cacheRate: 0)    0,268104170932519    0,007991
    umbracoMacro    Xslt node adding search start (palabra,'phurls')    0,268182661511136    0,000078
    umbracoMacro    Before adding extensions    0,268219320384189    0,000037
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltCommon, ExsltCommon    0,26828181770563    0,000062
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltDatesAndTimes, ExsltDatesAndTimes    0,268300968566816    0,000019
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltMath, ExsltMath    0,268317266108257    0,000016
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltRegularExpressions, ExsltRegularExpressions    0,2683329779382    0,000016
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltStrings, ExsltStrings    0,268348161199232    0,000015
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltSets, ExsltSets    0,268366927776534    0,000019
    umbracoXsltExtension    Extension added: urn:obtenerPropiedadTraducida, obtenerPropiedadTraducida    0,268382553892601    0,000016
    umbracoXsltExtension    Extension added: urn:ucomponents.urls, Urls    0,268398051914038    0,000015
    umbracoMacro    After adding extensions    0,268412147561202    0,000014
    umbracoMacro    Before performing transformation    0,268438296960491    0,000026
    umbracoMacro    After performing transformation    0,270151859753049    0,001714
    renderMacro    Rendering started (macro: BannerDerecho, type: 3, cacheRate: 0)    0,278220004190456    0,008068
    umbracoMacro    Usercontrol added (usercontrols/BannerDerecho.ascx)    0,278259014957072    0,000039
    loadUserControl    Usercontrol added with id 'BannerDerecho_8'    0,278425466545397    0,000166
    renderMacro    Rendering started (macro: EnlacesInteres, type: 3, cacheRate: 0)    0,286139778858196    0,007714
    umbracoMacro    Usercontrol added (usercontrols/EnlacesInteres.ascx)    0,286175105832829    0,000035
    loadUserControl    Usercontrol added with id 'EnlacesInteres_9'    0,286345664544455    0,000171
    renderMacro    Rendering started (macro: ControlDocumentos, type: 3, cacheRate: 0)    0,294116263732077    0,007771
    umbracoMacro    Usercontrol added (usercontrols/ControlDocumentos.ascx)    0,294151073566316    0,000035
    loadUserControl    Usercontrol added with id 'ControlDocumentos_10'    0,294325191308613    0,000174
    renderMacro    Rendering started (macro: Pie, type: 1, cacheRate: 0)    0,30218419674192    0,007859
    umbracoMacro    Before adding extensions    0,302254853072128    0,000071
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltCommon, ExsltCommon    0,302309961381136    0,000055
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltDatesAndTimes, ExsltDatesAndTimes    0,302328661768277    0,000019
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltMath, ExsltMath    0,302350610235189    0,000022
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltRegularExpressions, ExsltRegularExpressions    0,302366924919405    0,000016
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltStrings, ExsltStrings    0,302381932943176    0,000015
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltSets, ExsltSets    0,302397692868129    0,000016
    umbracoXsltExtension    Extension added: urn:obtenerPropiedadTraducida, obtenerPropiedadTraducida    0,302413189461003    0,000015
    umbracoXsltExtension    Extension added: urn:ucomponents.urls, Urls    0,302431545564069    0,000018
    umbracoMacro    After adding extensions    0,30244559121147    0,000014
    umbracoMacro    Before performing transformation    0,30247097490012    0,000025
    umbracoMacro    After performing transformation    0,302833378412484    0,000362
    aspx.page    End Init    0,303372632987462    0,000539
    aspx.page    Begin InitComplete    0,303399308098533    0,000027
    aspx.page    End InitComplete    0,30341473469174    0,000015
    aspx.page    Begin PreLoad    0,303434624120837    0,000020
    aspx.page    End PreLoad    0,303466526826063    0,000032
    aspx.page    Begin Load    0,303480752948796    0,000014
    aspx.page    End Load    5,99212598178104    5,688645

    aspx.page    Begin LoadComplete    5,99222529892715    0,000099
    aspx.page    End LoadComplete    5,99226830015095    0,000043
    aspx.page    Begin PreRender    5,9923048985481    0,000037
    aspx.page    End PreRender    5,99329513288032    0,000990
    aspx.page    Begin PreRenderComplete    5,99336671492041    0,000072
    aspx.page    End PreRenderComplete    5,99340852567369    0,000042
    aspx.page    Begin SaveState    5,99556975823925    0,002161
    aspx.page    End SaveState    5,99561508183294    0,000045
    aspx.page    Begin SaveStateComplete    5,99565063928267    0,000036
    aspx.page    End SaveStateComplete    5,99569636382684    0,000046
    aspx.page    Begin Render    5,99573219222766    0,000036
    aspx.page    End Render    6,01328251627373    0,017550

     

    And this is the trace in the Local Development Server for the same page:

     

    aspx.page    Begin PreInit       
    umbracoInit    handling request    5,7549213657043E-05    0,000058
    request handler    current url '/lang_castellano/aeropuertos/servidumbres/tramitacion/d_1844_1975_10jul.aspx'    0,000369320681818499    0,000312
    umbracoRequestHandler    xpath: '/root/*/* [@urlName = "lang_castellano"]/* [@urlName = "aeropuertos"]/* [@urlName = "servidumbres"]/* [@urlName = "tramitacion"]/* [@urlName = "d_1844_1975_10jul"]'    0,000469612758046065    0,000100
    umbracoRequestHandler    Just before xPath query (False, '/root/*/* [@urlName = "lang_castellano"]/* [@urlName = "aeropuertos"]/* [@urlName = "servidumbres"]/* [@urlName = "tramitacion"]/* [@urlName = "d_1844_1975_10jul"]')    0,000515149271764987    0,000046
    umbracoRequestHandler    pageXPathQueryStart: '/root'    0,000554819118072269    0,000040
    umbracoRequestHandler    Adding to cache... ('/lang_castellano/aeropuertos/servidumbres/tramitacion/d_1844_1975_10jul.aspx')    0,113250147714304    0,112695
    umbracoRequestHandler    After xPath query    0,11331859216744    0,000068
    umbracoRequestHandler    Access checking started    0,113373347729949    0,000055
    umbracoRequestHandler    Page not protected    0,11349403345956    0,000121
    umbracoRequestHandler    Access checking ended    0,113527557273341    0,000034
    umbracoInit    Done handling request    0,113566947754533    0,000039
    umbracoPage    Element loaded: phtitulo    0,114116738300538    0,000550
    umbracoPage    Element loaded: phdescripcion    0,114202224025679    0,000085
    umbracoPage    Element loaded: phdescripcionlarga    0,114253347841695    0,000051
    umbracoPage    Element loaded: phdocumento    0,114296649434495    0,000043
    umbracoPage    Element loaded: abrirdirectamente    0,114340509757525    0,000044
    umbracoPage    Element loaded: fecha    0,114397220875838    0,000057
    umbracoPage    Element loaded: displayname    0,114441081198867    0,000044
    umbracoPage    Element loaded: isimportant    0,114484382791668    0,000043
    umbracoPage    Pagedata loaded for d_1844_1975_10jul (ID: 29913)    0,114519303431023    0,000035
    umbracoPage    Looking up skin information    0,114569030421464    0,000050
    default.aspx    Culture changed to es-ES    0,115764433747865    0,001195
    aspx.page    End PreInit    0,116077881406715    0,000313
    aspx.page    Begin Init    0,11627846555917    0,000201
    renderMacro    Rendering started (macro: Cabecera, type: 3, cacheRate: 0)    0,118523722987139    0,002245
    umbracoMacro    Usercontrol added (/usercontrols/Cabecera.ascx)    0,118585183312404    0,000061
    loadUserControl    Usercontrol added with id 'Cabecera_1'    0,119450656438179    0,000865
    renderMacro    Rendering started (macro: MenuDG, type: 3, cacheRate: 0)    0,119969437456436    0,000519
    umbracoMacro    Usercontrol added (usercontrols/MenuDG.ascx)    0,120009107302744    0,000040
    loadUserControl    Usercontrol added with id 'MenuDG_2'    0,120619520078669    0,000610
    renderMacro    Rendering started (macro: BannerIzquierdo, type: 3, cacheRate: 0)    0,120983812188421    0,000364
    umbracoMacro    Usercontrol added (usercontrols/BannerIzquierdo.ascx)    0,121024599495187    0,000041
    loadUserControl    Usercontrol added with id 'BannerIzquierdo_3'    0,121897336113947    0,000873
    renderMacro    Rendering started (macro: Rastro Migas, type: 1, cacheRate: 0)    0,122301856800236    0,000405
    umbracoMacro    Before adding extensions    0,122409971099679    0,000108
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltCommon, ExsltCommon    0,122531494924634    0,000122
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltDatesAndTimes, ExsltDatesAndTimes    0,122572002866286    0,000041
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltMath, ExsltMath    0,122615025093971    0,000043
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltRegularExpressions, ExsltRegularExpressions    0,122653577479819    0,000039
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltStrings, ExsltStrings    0,122697717167964    0,000044
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltSets, ExsltSets    0,122739342570075    0,000042
    umbracoXsltExtension    Extension added: urn:obtenerPropiedadTraducida, obtenerPropiedadTraducida    0,122788790195402    0,000049
    umbracoXsltExtension    Extension added: urn:ucomponents.urls, Urls    0,122850809250896    0,000062
    umbracoMacro    After adding extensions    0,122884891794907    0,000034
    umbracoMacro    Before performing transformation    0,122964231487521    0,000079
    umbracoMacro    After performing transformation    0,124144549097721    0,001180
    renderMacro    Rendering started (macro: CabeceraCanal, type: 3, cacheRate: 0)    0,124493196761041    0,000349
    umbracoMacro    Usercontrol added (usercontrols/CabeceraCanal.ascx)    0,124533425337578    0,000040
    loadUserControl    Usercontrol added with id 'CabeceraCanal_5'    0,1251927270086    0,000659
    renderMacro    Rendering started (macro: MenuHorizontal, type: 3, cacheRate: 0)    0,130804613435506    0,005612
    umbracoMacro    Usercontrol added (usercontrols/MenuHorizontal.ascx)    0,130858530902671    0,000054
    loadUserControl    Usercontrol added with id 'MenuHorizontal_6'    0,131692435770468    0,000834
    renderMacro    Rendering started (macro: [XSLT]TraduccionPropiedadesContenido, type: 1, cacheRate: 0)    0,132351178711261    0,000659
    umbracoMacro    Xslt node adding search start (palabra,'phurls')    0,132421299355086    0,000070
    umbracoMacro    Before adding extensions    0,132479686664087    0,000058
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltCommon, ExsltCommon    0,132560423182276    0,000081
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltDatesAndTimes, ExsltDatesAndTimes    0,132600651758813    0,000040
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltMath, ExsltMath    0,132640042240005    0,000039
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltRegularExpressions, ExsltRegularExpressions    0,132678315260738    0,000038
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltStrings, ExsltStrings    0,132716588281472    0,000038
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltSets, ExsltSets    0,13275458193709    0,000038
    umbracoXsltExtension    Extension added: urn:obtenerPropiedadTraducida, obtenerPropiedadTraducida    0,132795369243856    0,000041
    umbracoXsltExtension    Extension added: urn:ucomponents.urls, Urls    0,132837553376197    0,000042
    umbracoMacro    After adding extensions    0,132873312110897    0,000036
    umbracoMacro    Before performing transformation    0,132918848624616    0,000046
    umbracoMacro    After performing transformation    0,135268588605535    0,002350
    renderMacro    Rendering started (macro: BannerDerecho, type: 3, cacheRate: 0)    0,135698252152159    0,000430
    umbracoMacro    Usercontrol added (usercontrols/BannerDerecho.ascx)    0,13573931882404    0,000041
    loadUserControl    Usercontrol added with id 'BannerDerecho_8'    0,136398899860178    0,000660
    renderMacro    Rendering started (macro: EnlacesInteres, type: 3, cacheRate: 0)    0,136774087209408    0,000375
    umbracoMacro    Usercontrol added (usercontrols/EnlacesInteres.ascx)    0,13681403642083    0,000040
    loadUserControl    Usercontrol added with id 'EnlacesInteres_9'    0,13739064601786    0,000577
    renderMacro    Rendering started (macro: ControlDocumentos, type: 3, cacheRate: 0)    0,137771141304272    0,000380
    umbracoMacro    Usercontrol added (usercontrols/ControlDocumentos.ascx)    0,137809414325005    0,000038
    loadUserControl    Usercontrol added with id 'ControlDocumentos_10'    0,138340487408316    0,000531
    renderMacro    Rendering started (macro: Pie, type: 1, cacheRate: 0)    0,138663712846186    0,000323
    umbracoMacro    Before adding extensions    0,138732157299322    0,000068
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltCommon, ExsltCommon    0,138794735085046    0,000063
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltDatesAndTimes, ExsltDatesAndTimes    0,138830493819745    0,000036
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltMath, ExsltMath    0,138870163666053    0,000040
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltRegularExpressions, ExsltRegularExpressions    0,138902849384489    0,000033
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltStrings, ExsltStrings    0,138935535102925    0,000033
    umbracoXsltExtension    Extension added: urn:Exslt.ExsltSets, ExsltSets    0,138974925584118    0,000039
    umbracoXsltExtension    Extension added: urn:obtenerPropiedadTraducida, obtenerPropiedadTraducida    0,139007890667669    0,000033
    umbracoXsltExtension    Extension added: urn:ucomponents.urls, Urls    0,139040576386105    0,000033
    umbracoMacro    After adding extensions    0,139072144644082    0,000032
    umbracoMacro    Before performing transformation    0,139113770046193    0,000042
    umbracoMacro    After performing transformation    0,139487560569849    0,000374
    aspx.page    End Init    0,140004944762533    0,000517
    aspx.page    Begin InitComplete    0,140039586036773    0,000035
    aspx.page    End InitComplete    0,140070874929635    0,000031
    aspx.page    Begin PreLoad    0,140105516203875    0,000035
    aspx.page    End PreLoad    0,140145186050182    0,000040
    aspx.page    Begin Load    0,140207484470792    0,000062
    aspx.page    End Load    0,502788203528661    0,362581

    aspx.page    Begin LoadComplete    0,502842679726055    0,000054
    aspx.page    End LoadComplete    0,502873130523572    0,000030
    aspx.page    Begin PreRender    0,502932355927918    0,000059
    aspx.page    End PreRender    0,503275136923827    0,000343
    aspx.page    Begin PreRenderComplete    0,503309219467837    0,000034
    aspx.page    End PreRenderComplete    0,50333883217001    0,000030
    aspx.page    Begin SaveState    0,504322476739362    0,000984
    aspx.page    End SaveState    0,504356279918258    0,000034
    aspx.page    Begin SaveStateComplete    0,504387289446005    0,000031
    aspx.page    End SaveStateComplete    0,504429473578346    0,000042
    aspx.page    Begin Render    0,504460762471208    0,000031
    aspx.page    End Render    0,513647404907607    0,009187

    Any idea?

  • Sergio 73 posts 93 karma points
    Nov 16, 2011 @ 12:52
    Sergio
    0

    I think the problem comes from the Examine Internal Indexer.

    In Windows Azure server there is no content indexed in the Internal Indexer. Even if I try to rebuild the Index manually (using Examine Dashboard package), nothing happens.

    Has somebody an Umbraco website working in Azure and with the internal indexer working?

Please Sign in or register to post replies

Write your reply to:

Draft