Hi ChrisC
yes I’ve check the log…
I can’t see any warning/error/fatal
Here the exact result after the “Print as PDF” click
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] current_language is: en_us
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCacheAPC
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCacheMemcache
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCacheRedis
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCacheFile
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCacheMemcached
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCacheZend
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCacheMemory
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Using cache backend SugarCacheMemory, since 999 is less than 1000
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCacheWincache
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Found cache backend SugarCachesMash
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Found language file: en_us.lang.php
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Found extended language file: en_us.lang.ext.php
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Found custom language file: en_us.lang.php
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query:SELECT id, name, symbol, conversion_rate FROM currencies WHERE status = 'Active' and deleted = 0
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query Execution Time:9.2029571533203E-5
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query:SELECT category, name, value FROM config
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query Execution Time:9.9897384643555E-5
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query:SELECT id FROM outbound_email WHERE type = 'system'
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query Execution Time:7.1048736572266E-5
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query:SELECT * FROM outbound_email WHERE id = '2e4d6ee9-ab7f-f901-a3aa-533c5799df75'
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query Execution Time:7.2956085205078E-5
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Hook called: ::after_entry_point
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Including module specific hook file for custom/modules
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Including Ext hook file for custom/application
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Hook called: ::after_session_start
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] We have an authenticated user id: 1
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Including module specific hook file for custom/modules/Users
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][-none-][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][-none-][INFO] Query Execution Time:9.8943710327148E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] get_user_array query: SELECT id, first_name, last_name, user_name from users WHERE 1=1 ORDER BY first_name, last_name ASC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT id, first_name, last_name, user_name from users WHERE 1=1 ORDER BY first_name, last_name ASC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.4161758422852E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT u1.first_name, u1.last_name from users u1, users u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.1062316894531E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT ea.email_address, ea.email_address_caps, ea.invalid_email, ea.opt_out, ea.date_created, ea.date_modified,
ear.id, ear.email_address_id, ear.bean_id, ear.bean_module, ear.primary_address, ear.reply_to_address, ear.deleted
FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
WHERE ear.bean_module = 'Users'
AND ear.bean_id = '1'
AND ear.deleted = 0
ORDER BY ear.reply_to_address, ear.primary_address DESC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.5830688476562E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Current user is: admin
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Current user is: admin
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] setting cookie ck_login_id_20 to 1
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] setting cookie ck_login_language_20 to en_us
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] current_language is: en_us
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Found language file: en_us.lang.php
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Found extended language file: en_us.lang.ext.php
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Found custom language file: en_us.lang.php
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT count(*) as the_count FROM config WHERE category='info' AND name='sugar_version' AND value = '6.5.16'
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:0.00010418891906738
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT category, name, value FROM config WHERE category = 'license'
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:7.8916549682617E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT id FROM outbound_email WHERE type = 'system'
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:7.4863433837891E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT * FROM outbound_email WHERE id = '2e4d6ee9-ab7f-f901-a3aa-533c5799df75'
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:7.8916549682617E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_Invoices::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve AOS_Invoices : SELECT aos_invoices.* FROM aos_invoices WHERE aos_invoices.id = '18a6d2df-0bf5-a030-76ac-533ec63b8493' AND aos_invoices.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT aos_invoices.* FROM aos_invoices WHERE aos_invoices.id = '18a6d2df-0bf5-a030-76ac-533ec63b8493' AND aos_invoices.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT aos_invoices.* FROM aos_invoices WHERE aos_invoices.id = '18a6d2df-0bf5-a030-76ac-533ec63b8493' AND aos_invoices.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.8930130004883E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[AOS_Invoices].load_relationships, Loading relationship (billing_account_id).
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean.load_relationships, Error Loading relationship (billing_account_id)
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[AOS_Invoices].load_relationships, Loading relationship (billing_contact_id).
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean.load_relationships, Error Loading relationship (billing_contact_id)
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_Invoices::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT pg.id, pg.product_id, pg.group_id FROM aos_products_quotes pg LEFT JOIN aos_line_item_groups lig ON pg.group_id = lig.id WHERE pg.parent_type = 'AOS_Invoices' AND pg.parent_id = '18a6d2df-0bf5-a030-76ac-533ec63b8493' AND pg.deleted = 0 ORDER BY lig.number ASC, pg.number ASC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.4161758422852E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_PDF_Templates::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve AOS_PDF_Templates : SELECT aos_pdf_templates.* FROM aos_pdf_templates WHERE aos_pdf_templates.id = 'b26c07d5-a6bb-db10-da6d-533ec6b24671' AND aos_pdf_templates.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT aos_pdf_templates.* FROM aos_pdf_templates WHERE aos_pdf_templates.id = 'b26c07d5-a6bb-db10-da6d-533ec6b24671' AND aos_pdf_templates.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT aos_pdf_templates.* FROM aos_pdf_templates WHERE aos_pdf_templates.id = 'b26c07d5-a6bb-db10-da6d-533ec6b24671' AND aos_pdf_templates.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:7.1048736572266E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_PDF_Templates::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_Products_Quotes::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve AOS_Products_Quotes : SELECT aos_products_quotes.* FROM aos_products_quotes WHERE aos_products_quotes.id = '387a6d66-1e2f-4e07-5792-533ec688cec0' AND aos_products_quotes.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT aos_products_quotes.* FROM aos_products_quotes WHERE aos_products_quotes.id = '387a6d66-1e2f-4e07-5792-533ec688cec0' AND aos_products_quotes.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT aos_products_quotes.* FROM aos_products_quotes WHERE aos_products_quotes.id = '387a6d66-1e2f-4e07-5792-533ec688cec0' AND aos_products_quotes.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:0.00010395050048828
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT id ,aos_invoices.name as parent_name , aos_invoices.assigned_user_id owner FROM aos_invoices WHERE deleted=0 AND id='18a6d2df-0bf5-a030-76ac-533ec63b8493'
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:9.7990036010742E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_Products_Quotes::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.702278137207E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT u1.first_name, u1.last_name from users u1, users u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:7.7962875366211E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT ea.email_address, ea.email_address_caps, ea.invalid_email, ea.opt_out, ea.date_created, ea.date_modified,
ear.id, ear.email_address_id, ear.bean_id, ear.bean_module, ear.primary_address, ear.reply_to_address, ear.deleted
FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
WHERE ear.bean_module = 'Users'
AND ear.bean_id = '1'
AND ear.deleted = 0
ORDER BY ear.reply_to_address, ear.primary_address DESC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:6.103515625E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:7.7962875366211E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT u1.first_name, u1.last_name from users u1, users u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.392333984375E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT ea.email_address, ea.email_address_caps, ea.invalid_email, ea.opt_out, ea.date_created, ea.date_modified,
ear.id, ear.email_address_id, ear.bean_id, ear.bean_module, ear.primary_address, ear.reply_to_address, ear.deleted
FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
WHERE ear.bean_module = 'Users'
AND ear.bean_id = '1'
AND ear.deleted = 0
ORDER BY ear.reply_to_address, ear.primary_address DESC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.9883804321289E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:8.5115432739258E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT u1.first_name, u1.last_name from users u1, users u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:7.6055526733398E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT ea.email_address, ea.email_address_caps, ea.invalid_email, ea.opt_out, ea.date_created, ea.date_modified,
ear.id, ear.email_address_id, ear.bean_id, ear.bean_module, ear.primary_address, ear.reply_to_address, ear.deleted
FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
WHERE ear.bean_module = 'Users'
AND ear.bean_id = '1'
AND ear.deleted = 0
ORDER BY ear.reply_to_address, ear.primary_address DESC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.793571472168E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.2928924560547E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT u1.first_name, u1.last_name from users u1, users u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.4121017456055E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT ea.email_address, ea.email_address_caps, ea.invalid_email, ea.opt_out, ea.date_created, ea.date_modified,
ear.id, ear.email_address_id, ear.bean_id, ear.bean_module, ear.primary_address, ear.reply_to_address, ear.deleted
FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
WHERE ear.bean_module = 'Users'
AND ear.bean_id = '1'
AND ear.deleted = 0
ORDER BY ear.reply_to_address, ear.primary_address DESC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:4.9114227294922E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_Products::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve AOS_Products : SELECT aos_products.* FROM aos_products WHERE aos_products.id = 'addb1a81-48d6-4127-bc45-533cfc1daac0' AND aos_products.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT aos_products.* FROM aos_products WHERE aos_products.id = 'addb1a81-48d6-4127-bc45-533cfc1daac0' AND aos_products.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT aos_products.* FROM aos_products WHERE aos_products.id = 'addb1a81-48d6-4127-bc45-533cfc1daac0' AND aos_products.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:6.0081481933594E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[AOS_Products].load_relationships, Loading relationship (assigned_user_id).
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean.load_relationships, Error Loading relationship (assigned_user_id)
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[AOS_Products].load_relationships, Loading relationship (assigned_user_id).
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean.load_relationships, Error Loading relationship (assigned_user_id)
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[AOS_Products].load_relationships, Loading relationship (contact_id).
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean.load_relationships, Error Loading relationship (contact_id)
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_Product_Categories::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve AOS_Product_Categories : SELECT aos_product_categories.* FROM aos_product_categories WHERE aos_product_categories.id = 'ac7e1c54-55df-dfd4-46fa-533cfbd96a38' AND aos_product_categories.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT aos_product_categories.* FROM aos_product_categories WHERE aos_product_categories.id = 'ac7e1c54-55df-dfd4-46fa-533cfbd96a38' AND aos_product_categories.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT aos_product_categories.* FROM aos_product_categories WHERE aos_product_categories.id = 'ac7e1c54-55df-dfd4-46fa-533cfbd96a38' AND aos_product_categories.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.6982040405273E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[AOS_Product_Categories].load_relationships, Loading relationship (sub_product_categories).
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean.load_relationships, Error Loading relationship (sub_product_categories)
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_Product_Categories::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT id ,aos_product_categories.name as aos_product_category_name , aos_product_categories.assigned_user_id owner FROM aos_product_categories WHERE deleted=0 AND id='ac7e1c54-55df-dfd4-46fa-533cfbd96a38'
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:4.4107437133789E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: AOS_Products::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:6.3896179199219E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT u1.first_name, u1.last_name from users u1, users u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:4.3869018554688E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT ea.email_address, ea.email_address_caps, ea.invalid_email, ea.opt_out, ea.date_created, ea.date_modified,
ear.id, ear.email_address_id, ear.bean_id, ear.bean_module, ear.primary_address, ear.reply_to_address, ear.deleted
FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
WHERE ear.bean_module = 'Users'
AND ear.bean_id = '1'
AND ear.deleted = 0
ORDER BY ear.reply_to_address, ear.primary_address DESC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.793571472168E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT users.* FROM users WHERE users.id = '1' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.6028366088867E-5
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT u1.first_name, u1.last_name from users u1, users u2 where u1.id = u2.reports_to_id AND u2.id = '1' and u1.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.4121017456055E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] SugarBean[User].load_relationships, Loading relationship (reports_to_link).
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT ea.email_address, ea.email_address_caps, ea.invalid_email, ea.opt_out, ea.date_created, ea.date_modified,
ear.id, ear.email_address_id, ear.bean_id, ear.bean_module, ear.primary_address, ear.reply_to_address, ear.deleted
FROM email_addresses ea LEFT JOIN email_addr_bean_rel ear ON ea.id = ear.email_address_id
WHERE ear.bean_module = 'Users'
AND ear.bean_id = '1'
AND ear.deleted = 0
ORDER BY ear.reply_to_address, ear.primary_address DESC
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.0067901611328E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::after_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT users.* FROM users WHERE users.id = '' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:6.2942504882812E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Users::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve User : SELECT users.* FROM users WHERE users.id = '' AND users.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT users.* FROM users WHERE users.id = '' AND users.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT users.* FROM users WHERE users.id = '' AND users.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:5.3882598876953E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: Contacts::before_retrieve
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Including module specific hook file for custom/modules/Contacts
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Retrieve Contact : SELECT contacts.*,contacts_cstm.* FROM contacts LEFT JOIN contacts_cstm ON contacts.id = contacts_cstm.id_c WHERE contacts.id = '' AND contacts.deleted=0
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Limit Query:SELECT contacts.*,contacts_cstm.* FROM contacts LEFT JOIN contacts_cstm ON contacts.id = contacts_cstm.id_c WHERE contacts.id = '' AND contacts.deleted=0 Start: 0 count: 1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query:SELECT contacts.*,contacts_cstm.* FROM contacts LEFT JOIN contacts_cstm ON contacts.id = contacts_cstm.id_c WHERE contacts.id = '' AND contacts.deleted=0 LIMIT 0,1
Fri Apr 4 19:58:36 2014 [25219][1][INFO] Query Execution Time:9.2029571533203E-5
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Hook called: ::server_round_trip
Fri Apr 4 19:58:36 2014 [25219][1][DEBUG] Calling MySQLi::disconnect()