[ic] PostgreSQL 8.3

Rick Bragg lists at gmnet.net
Thu Jun 19 18:16:05 UTC 2008


On Thu, 2008-06-19 at 11:10 -0600, Jon Jensen wrote:
> On Thu, 19 Jun 2008, Rick Bragg wrote:
> 
> > 2008-06-19 08:57:11 EDT LOG:  00000: statement: SELECT * FROM country WHERE 2 = 1
> > 2008-06-19 08:57:11 EDT LOCATION:  exec_simple_query, postgres.c:845
> > 2008-06-19 08:57:11 EDT LOG:  00000: duration: 0.606 ms
> > 2008-06-19 08:57:11 EDT LOCATION:  exec_simple_query, postgres.c:1051
> > 2008-06-19 08:57:11 EDT LOG:  00000: statement: SELECT * FROM categories WHERE 2 = 1
> > 2008-06-19 08:57:11 EDT LOCATION:  exec_simple_query, postgres.c:845
> > 2008-06-19 08:57:11 EDT LOG:  00000: duration: 13.645 ms
> > 2008-06-19 08:57:11 EDT LOCATION:  exec_simple_query, postgres.c:1051
> > 2008-06-19 08:57:11 EDT LOG:  00000: statement: SELECT * FROM affiliate WHERE 2 = 1
> > 2008-06-19 08:57:11 EDT LOCATION:  exec_simple_query, postgres.c:845
> > 2008-06-19 08:57:11 EDT LOG:  00000: duration: 1.026 ms
> > 2008-06-19 08:57:11 EDT LOCATION:  exec_simple_query, postgres.c:1051
> > 2008-06-19 08:59:07 EDT LOG:  08P01: unexpected EOF on client connection
> > 2008-06-19 08:59:07 EDT LOCATION:  SocketBackend, postgres.c:323
> > 2008-06-19 08:59:07 EDT LOG:  00000: disconnection: session time: 0:01:56.460 user=harris database=harrisdev host=[local]
> > 2008-06-19 08:59:07 EDT LOCATION:  log_disconnections, postgres.c:3958
> >
> > There are a bunch of these statements (one for each table it seems) then 
> > it hangs. then when I kill IC, it generates the last 4 lines of the 
> > above.
> >
> > I'm sure it is something with my catalog (not Pg, or IC) because this is 
> > a migration from 5.4.2...
> 
> Something seriously odd is going on. I recommend you turn on DBI DataTrace 
> in interchange.cfg so you can see what SQL is coming from the client side 
> at the time your Interchange server is hanging, and try to pair that with 
> the Postgres log.
> 
> Jon
> 
> -- 
> Jon Jensen
> End Point Corporation
> http://www.endpoint.com/

It is odd.  I turned on DataTrace.  The logs are the same, but here is
what happend at the command when I restart IC


# su interchange -c "PERL_SIGNALS=unsafe /usr/local/interchange-5.6.0-dev-ppsd/bin/interchange --restart"
The previous Interchange server was not running and probably
terminated with an error.
Vend::Payment::AuthorizeNet payment module initialized, using Net::SSLeay
Vend::Payment::Linkpoint payment module initialized
Sending debug messages to debug.log.
Low traffic settings.
Calling UI......UI is loaded...
Interchange V5.6.0
Running with old signals.
Configuring catalog hcdev...Using PostgreSQL, DSN=dbi:Pg:database=harrisdev...    DBI::db=HASH(0x40c7670) trace level set to 0x0/1 (DBI @ 0x0/0) in DBI 1.604-ithread (pid 31851)
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM tree WHERE 2 = 1')= DBI::st=HASH(0x40c7b80) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'parent_fld' 'mgroup' 'msort' 'page' 'form' 'name' 'description' 'inactive' 'extended' 'next_line' 'indicator' 'exclude_on' 'depends_on' 'super' 'help_name' 'img_dn' 'img_up' 'img_sel' 'img_icon' 'url' 'member' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40c7800))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM state WHERE 2 = 1')= DBI::st=HASH(0x7fa250) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'sorder' 'country' 'state' 'name' 'tax' 'postcode' 'shipmodes' 'minimum_order' 'tax_name' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40c7a10))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM ups_cache WHERE 2 = 1')= DBI::st=HASH(0x40c7cc0) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'weight' 'origin' 'zip' 'country' 'shipmode' 'cost' 'updated' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(3e91d90))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM products WHERE 2 = 1')= DBI::st=HASH(0x40da3c0) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'sku' 'description' 'extended' 'comment' 'metakeywords' 'metadescription' 'thumb' 'image1' 'image_large1' 'image2' 'image_large2' 'image3' 'image_large3' 'parentcode' 'categorycode' 'brand' 'color' 'size' 'detail1label' 'detail2label' 'detail3label' 'url' 'template_page' 'related' 'featured' 'price' 'sale_price' 'wholesale' 'cost' 'affiliate' 'percentage' 'option_type' 'tax_category' 'nontaxable' 'unit' 'weight' 'tour' 'tourcode' 'gift_cert' 'donation' 'special' ... 7 others skipped ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40da700))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM order_returns WHERE 2 = 1')= DBI::st=HASH(0x40da8b0) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'order_number' 'session' 'username' 'rma_number' 'nitems' 'total' 'return_date' 'update_date' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40dac10))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM pay_cert_redeem WHERE 2 = 1')= DBI::st=HASH(0x40dadd0) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'pay_id' 'link_tid' 'trans_date' 'ip_addr' 'trans_type' 'voided' 'captured' 'username' 'amount' 'items' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40dec10))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM pay_cert_lock WHERE 2 = 1')= DBI::st=HASH(0x40dedd0) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'pid' 'ip_addr' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40df170))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM pricing WHERE 2 = 1')= DBI::st=HASH(0x40df330) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'sku' 'price_group' 'q5' 'q10' 'w75' 'w100' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40e1d20))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM merchandising WHERE 2 = 1')= DBI::st=HASH(0x40e1ee0) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'sku' 'featured' 'banner_text' 'banner_image' 'blurb_begin' 'blurb_end' 'timed_promotion' 'start_date' 'finish_date' 'upsell_to' 'cross_sell' 'cross_category' 'others_bought' 'times_ordered' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40e2190))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('ChopBlanks', '1')= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM options WHERE 2 = 1')= DBI::st=HASH(0x40e2350) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'sku' 'o_group' 'o_sort' 'o_default' 'o_label' 'o_value' 'o_widget' 'o_height' 'o_width' 'description' 'price' 'wholesale' 'o_simple' 'display_type' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40e2650))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM forum WHERE 2 = 1')= DBI::st=HASH(0x40e2810) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'parent' 'artid' 'mod_time' 'created' 'username' 'email' 'name' 'host' 'score' 'lastscore' 'reason' 'anon' 'extended' 'subject' 'comment' 'additional' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40e8db0))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM transactions WHERE 2 = 1')= DBI::st=HASH(0x40e8f70) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'store_id' 'order_number' 'session' 'username' 'shipmode' 'nitems' 'subtotal' 'shipping' 'handling' 'salestax' 'total_cost' 'fname' 'lname' 'company' 'address1' 'address2' 'city' 'state' 'zip' 'country' 'phone_day' 'phone_night' 'fax' 'email' 'b_fname' 'b_lname' 'b_company' 'b_address1' 'b_address2' 'b_city' 'b_state' 'b_zip' 'b_country' 'b_phone' 'order_date' 'order_ymd' 'order_wday' 'payment_method' 'po_number' 'avs' ... 13 others skipped ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40e9400))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM inventory WHERE 2 = 1')= DBI::st=HASH(0x40e95c0) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'sku' 'quantity' 'stock_message' 'account' 'cogs_account' 'min_order' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40ee5d0))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM country WHERE 2 = 1')= DBI::st=HASH(0x40ee790) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'sorder' 'region' 'selector' 'shipmodes' 'minimum_order' 'name' 'iso' 'isonum' 'tax' 'no_state' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40ef3d0))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM categories WHERE 2 = 1')= DBI::st=HASH(0x40f3990) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'code' 'parentcode' 'description' 'extended' 'comment' 'metakeywords' 'metadescription' 'thumb' 'image' 'image_large' 'showitems' 'subcolumns' 'sort' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40f3c90))= undef at DBI.pm line 686
    <- FETCH('Driver')= DBI::dr=HASH(0x3fd0380) at DBI.pm line 661
    <- STORE('RaiseError', 1)= 1 at DBI.pm line 676
    <- STORE('PrintError', 0)= 1 at DBI.pm line 676
    <- prepare('SELECT * FROM affiliate WHERE 2 = 1')= DBI::st=HASH(0x40f3e50) at DBI.pm line 1805
    <- execute= '0E0' at DBI.pm line 1809
    <- FETCH('NAME')= [ 'affiliate' 'password' 'join_date' 'company' 'fname' 'lname' 'address1' 'address2' 'address3' 'city' 'state' 'zip' 'country' 'phone_day' 'phone_night' 'email' 'fax' 'campaigns' 'coupon_amount' 'image' 'url' 'content' 'timeout' 'payout' 'percentage' 'balance' 'charity' 'weblist' 'active' ] at DBI.pm line 1815
    <- DESTROY(DBI::st=HASH(40f42e0))= undef at DBI.pm line 686

This is where it hangs until I kill it with ctl-c...

Any ideas?
Thanks!
rick





-- 
This message has been scanned for viruses and
dangerous content by Green Mountain Network, and is
believed to be clean.




More information about the interchange-users mailing list