Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2020 16:31
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17629056 kB
MemAvailable 31550640 kB
Buffers 0 kB
Cached 14049568 kB
SwapCached 5152 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.7
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-20 16:30:41
DTS_END                         2020-10-20 16:30:41.9530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/58266
TRN_ID                          242434
ATT_ID                          694
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9269.09 20561 1112291
customer order: creation 182.18 344 21861
customer order: refuse 15.63 373 1876
order to supplier: creation 47.24 1303 5669
order to supplier: removal 3.41 103983 409
invoice (draft): creation 48.22 3369 5786
invoice (draft): removal 49.76 815 5971
invoice accept: apply 44.57 18105 5348
invoice accept: cancel 44.81 849 5377
customer reserve: creation 4119.37 179 494324
customer reserve: removal 4416.61 64 529993
realization accept: apply 87.64 169 10517
realization accept: cancel 73.40 117 8808
payment from customer: creation 61.21 103 7345
payment from customer: removal 5.98 77 717
payment to supplier: creation 52.23 108 6268
payment to supplier: removal 3.38 66 406
service: total inventory turnovers 5.18 1115 622
service: total monetary turnovers 7.31 37 877
service: aggregate perf. data 0.94 17568 113
service: refresh index statistics 0.03 262476 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2020 16:31:57 to 20-10-2020 16:31:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2598
TEST_TIME 2 4545
TEST_TIME 3 4449
TEST_TIME 4 4837
TEST_TIME 5 4879
TEST_TIME 6 5090
TEST_TIME 7 4998
TEST_TIME 8 4917
TEST_TIME 9 4950
TEST_TIME 10 5000
TEST_TIME 11 5098
TEST_TIME 12 5587
TEST_TIME 13 5671
TEST_TIME 14 5757
TEST_TIME 15 5878
TEST_TIME 16 6197
TEST_TIME 17 6641
TEST_TIME 18 6953
TEST_TIME 19 7181
TEST_TIME 20 7332
TEST_TIME 21 7753
TEST_TIME 22 8269
TEST_TIME 23 8520
TEST_TIME 24 8583
TEST_TIME 25 8614
TEST_TIME 26 9002
TEST_TIME 27 9321
TEST_TIME 28 9652
TEST_TIME 29 10203
TEST_TIME 30 10491
TEST_TIME 31 10628
TEST_TIME 32 10579
TEST_TIME 33 10430
TEST_TIME 34 10331
TEST_TIME 35 10265
TEST_TIME 36 10207
TEST_TIME 37 10117
TEST_TIME 38 10142
TEST_TIME 39 9948
TEST_TIME 40 9872
TEST_TIME 41 9747
TEST_TIME 42 9769
TEST_TIME 43 9943
TEST_TIME 44 9970
TEST_TIME 45 9981
TEST_TIME 46 9906
TEST_TIME 47 9830
TEST_TIME 48 9668
TEST_TIME 49 9594
TEST_TIME 50 9590
TEST_TIME 51 9604
TEST_TIME 52 9534
TEST_TIME 53 9541
TEST_TIME 54 9488
TEST_TIME 55 9569
TEST_TIME 56 9546
TEST_TIME 57 9566
TEST_TIME 58 9659
TEST_TIME 59 9699
TEST_TIME 60 9672
TEST_TIME 61 9631
TEST_TIME 62 9738
TEST_TIME 63 9776
TEST_TIME 64 9869
TEST_TIME 65 9981
TEST_TIME 66 10043
TEST_TIME 67 9908
TEST_TIME 68 9830
TEST_TIME 69 9755
TEST_TIME 70 9744
TEST_TIME 71 9686
TEST_TIME 72 9623
TEST_TIME 73 9608
TEST_TIME 74 9493
TEST_TIME 75 9491
TEST_TIME 76 9443
TEST_TIME 77 9503
TEST_TIME 78 9505
TEST_TIME 79 9468
TEST_TIME 80 9482
TEST_TIME 81 9451
TEST_TIME 82 9501
TEST_TIME 83 9571
TEST_TIME 84 9560
TEST_TIME 85 9550
TEST_TIME 86 9514
TEST_TIME 87 9633
TEST_TIME 88 9628
TEST_TIME 89 9617
TEST_TIME 90 9531
TEST_TIME 91 9501
TEST_TIME 92 9468
TEST_TIME 93 9463
TEST_TIME 94 9399
TEST_TIME 95 9345
TEST_TIME 96 9419
TEST_TIME 97 9493
TEST_TIME 98 9473
TEST_TIME 99 9444
TEST_TIME 100 9476
TEST_TIME 101 9459
TEST_TIME 102 9484
TEST_TIME 103 9464
TEST_TIME 104 9488
TEST_TIME 105 9523
TEST_TIME 106 9486
TEST_TIME 107 9548
TEST_TIME 108 9561
TEST_TIME 109 9479
TEST_TIME 110 9420
TEST_TIME 111 9391
TEST_TIME 112 9386
TEST_TIME 113 9346
TEST_TIME 114 9319
TEST_TIME 115 9292
TEST_TIME 116 9299
TEST_TIME 117 9308
TEST_TIME 118 9288
TEST_TIME 119 9282
TEST_TIME 120 9276

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-10-2020 16:32:00 to 20-10-2020 16:32:04.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1566700 1566700 0 0.00 0 13743 6 0 0
doc_list_biud 1566699 1566699 0 0.00 0 41540 27 0 0
sp_add_invoice_to_stock 5503 5348 155 2.81 40 177044 18105 0 155
sp_cancel_adding_invoice 5377 5377 0 0.00 6 89455 850 0 0
sp_cancel_client_order 1876 1876 0 0.00 5 16533 374 0 0
sp_cancel_customer_reserve 529993 529993 0 0.00 1 23297 65 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 3 2524 77 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 3339 67 0 0
sp_cancel_supplier_invoice 5978 5971 7 0.11 2 14593 815 0 7
sp_cancel_supplier_order 409 409 0 0.00 7 1160302 103983 0 0
sp_cancel_write_off 8808 8808 0 0.00 1 13098 118 0 0
sp_client_order 21861 21861 0 0.00 3 16098 344 0 0
sp_customer_reserve 494324 494324 0 0.00 0 145788 179 0 0
sp_fill_shopping_cart 33299 33299 0 0.00 1 12776 38 0 0
sp_fill_shopping_cart_clo_res 494312 494312 0 0.00 0 14634 14 0 0
sp_get_clo_for_invoice 5330 5330 0 0.00 0 19758 288 0 0
sp_kill_cost_storno 15308 15308 0 0.00 0 6292 55 0 0
sp_kill_qstorno_ret_qs2qd 1075174 1075174 0 0.00 0 26991 35 0 0
sp_kill_qty_storno 568279 568279 0 0.00 0 41538 74 0 0
sp_lock_selected_doc 21906 21906 0 0.00 0 9037 6 0 0
sp_make_cost_storno 29458 29458 0 0.00 0 13739 51 0 0
sp_multiply_rows_for_pdistr 29458 29458 0 0.00 0 6477 26 0 0
sp_multiply_rows_for_qdistr 21861 21861 0 0.00 0 10772 164 0 0
sp_pay_from_customer 7345 7345 0 0.00 4 8178 104 0 0
sp_pay_to_supplier 6268 6268 0 0.00 4 13791 108 0 0
sp_payment_common 13613 13613 0 0.00 1 12263 24 0 0
sp_qd_handle_on_cancel_clo 1876 1876 0 0.00 0 16524 340 0 0
sp_qd_handle_on_invoice_upd_sts 10705 10705 0 0.00 0 41537 317 0 0
sp_qd_handle_on_reserve_upd_sts 10517 10517 0 0.00 0 17332 51 0 0
sp_reserve_write_off 10517 10517 0 0.00 6 17745 169 0 0
sp_supplier_invoice 5786 5786 0 0.00 6 80901 3369 0 0
sp_supplier_order 5669 5669 0 0.00 6 28637 1304 0 0
srv_aggregate_perf_data 113 113 0 0.00 2103 294471 17568 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2297 144103 44494 0 0
srv_find_qd_qs_mism 1060131 1060131 0 0.00 0 13324 6 0 0
srv_make_invnt_saldo 622 622 0 0.00 39 132278 1115 0 0
srv_make_money_saldo 877 877 0 0.00 1 8233 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 158070 369295 262476 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5348 5348 0 0.00 0 10993 149 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 4036 96 0 0
t$perf-norm:sp_cancel_client_order 1876 1876 0 0.00 0 1597 9 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 4314 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 1103 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 160 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 2144 16 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 19222 1637 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 5851 15 0 0
t$perf-norm:sp_client_order 21861 21861 0 0.00 0 7688 18 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 4933 14 0 0
t$perf-norm:sp_pay_from_customer 7345 7345 0 0.00 0 8696 10 0 0
t$perf-norm:sp_pay_to_supplier 6268 6268 0 0.00 0 18806 17 0 0
t$perf-norm:sp_reserve_write_off 10517 10517 0 0.00 0 8021 11 0 0
t$perf-norm:sp_supplier_invoice 5786 5786 0 0.00 0 6346 20 0 0
t$perf-norm:sp_supplier_order 5669 5669 0 0.00 0 4478 17 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 510 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 36 1 0 0
t$perf-norm:srv_make_invnt_saldo 622 622 0 0.00 0 7708 41 0 0
t$perf-norm:srv_make_money_saldo 877 877 0 0.00 0 5423 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 69 19 0 0
v_add_invoice_to_stock 10966 10656 310 2.82 0 6559 18 0 310
v_all_customers 39330 39330 0 0.00 0 58 1 0 0
v_all_suppliers 32118 32118 0 0.00 0 31 1 0 0
v_all_wares 43722 43722 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 2869 11 0 0
v_cancel_client_order 3752 3752 0 0.00 0 4735 5 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 2447 10 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 2610 8 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 989 5 0 0
v_cancel_supplier_invoice 822 808 14 1.70 0 1875 21 0 14
v_cancel_supplier_order 818 818 0 0.00 0 2907 7 0 0
v_cancel_write_off 2110 2110 0 0.00 0 2088 13 0 0
v_max_id_clo_ord 5668 5668 0 0.00 0 10151 3 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 2285 13 0 0
v_max_id_ord_sup 5768 5768 0 0.00 0 6956 7 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 311 2 0 0
v_max_non_paid_realizn 3983 3983 0 0.00 0 4337 5 0 0
v_min_id_clo_ord 5668 5668 0 0.00 0 2455 5 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 1836 7 0 0
v_min_id_ord_sup 5767 5767 0 0.00 0 4700 6 0 0
v_min_non_paid_invoice 6268 6268 0 0.00 0 12259 15 0 0
v_min_non_paid_realizn 7345 7345 0 0.00 0 6044 15 0 0
v_our_firm 11116 11116 0 0.00 0 19 1 0 0
v_reserve_write_off 21034 21034 0 0.00 0 6272 6 0 0
x_lock_depdocs_on_canc_invoice 10944 10944 0 0.00 0 14471 110 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 21750 450 0 0
x_make_qstorno_1000_1200 5669 5669 0 0.00 1 28630 1230 0 0
x_make_qstorno_1200_2000 5786 5786 0 0.00 3 80891 3221 0 0
x_make_qstorno_2100_3300 494324 494324 0 0.00 0 145787 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2020 16:32:04 to 20-10-2020 16:32:08.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:00:45 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
14:01:46 23247 8192000000 10344215456 10661769216 1398454720 14.581 100 87 174 0 1433330592 6333584 28542288
14:03:14 21848 8192000000 10442536624 10774065152 1485539024 15.350 100 87 174 2 1522285408 8519904 28226480
14:04:37 23361 8192000000 10485294896 10829705216 1538060720 15.807 100 83 166 0 1574346400 9881008 26404672
14:06:11 14055 8192000000 10552316208 10912964608 1618549760 16.498 100 85 170 0 1655121632 10953328 25618544
14:07:30 8617 8192000000 10619143008 10986057728 1679848784 17.016 100 82 164 2 1716348272 13189680 23309808
14:08:42 5428 8192000000 10677776304 11047243776 1730740160 17.442 100 80 160 1 1767372016 14012000 22619856
14:09:49 5913 8192000000 10712873776 11099496448 1778306224 17.836 100 74 148 0 1812544720 13835840 20402656
14:11:01 2129 8192000000 10777352336 11168976896 1843976768 18.373 100 86 173 1 1880420192 11432640 25010784
14:12:05 1521 8192000000 10818034816 11207331840 1874945904 18.624 100 92 181 2 1913841808 13358928 25536976
14:13:08 5696 8192000000 10794423504 11212816384 1877129504 18.642 100 43 86 0 1897561200 8176224 12255472
14:14:15 15867 8192000000 10782201904 11220602880 1883521680 18.694 100 16 32 2 1893673664 5539504 4612480
14:15:32 11560 8192000000 10811891632 11238539264 1895896864 18.793 100 19 38 3 1907111120 5928992 5285264
14:16:44 36494 8192000000 10863028336 11271077888 1914710448 18.944 100 79 161 0 1945362864 6834688 23817728
14:18:21 6252 8192000000 10852311408 11266326528 1910352560 18.909 100 42 84 2 1930703056 8370080 11980416
14:19:28 7264 8192000000 10824575408 11262783488 1912998336 18.931 100 20 39 2 1924613376 5709248 5905792
14:20:38 33383 8192000000 10810922608 11252465664 1887689456 18.727 99 15 30 1 1901334960 9541104 4104400
14:22:13 10157 8192000000 10749621920 11179266048 1814426736 18.132 97 21 42 2 1825814752 5874960 5513056
14:23:26 7321 8192000000 10700101360 11118243840 1759033664 17.676 98 17 34 3 1770264864 6442816 4788384
14:24:36 14984 8192000000 10666080688 11078529024 1627715904 16.575 91 21 42 0 1639234368 5179264 6339200
14:25:52 23533 8192000000 10708001312 11120148480 1695858432 17.150 94 26 52 2 1708261888 5237600 7165856
14:27:19 9244 8192000000 10750840992 11170193408 1832338352 18.278 100 29 58 0 1846378128 5080480 8959296
14:28:30 13064 8192000000 10761728576 11192360960 1853328464 18.449 100 21 42 0 1865342112 5278000 6735648
14:29:43 63185 8192000000 10804479840 11217862656 1872223904 18.602 100 32 60 1 1885459536 4975280 8260352
14:32:01 12247 8192000000 10850595296 11266285568 1911456928 18.918 100 58 116 2 1938008368 10290848 16260592
14:33:18 26408 8192000000 10859401104 11289157632 1930153632 19.068 100 44 88 2 1953756176 10669584 12932960
14:34:52 10425 8192000000 10892773712 11323494400 1958013264 19.290 100 47 91 1 1986107216 14884768 13209184
14:36:13 26881 8192000000 10914412256 11346542592 1972660528 19.407 100 46 89 0 2004563712 18012768 13890416
14:37:56 47420 8192000000 10930539312 11370405888 1986113856 19.513 100 40 80 4 2020139840 21151360 12874624
14:39:57 3444 8192000000 10963653360 11385536512 2007561328 19.682 100 66 132 2 2051744624 23841248 20342048
14:41:06 9553 8192000000 10965694704 11393650688 2005391984 19.665 100 60 119 0 2051749792 28173152 18184656
14:42:30 7902 8192000000 10979141248 11410370560 2016045840 19.749 100 56 112 0 2064786960 31122384 17618736
14:43:44 7364 8192000000 10993648752 11426160640 2032762960 19.880 100 73 147 0 2083976496 29491440 21722096
14:44:59 6855 8192000000 11002284944 11427094528 2026246192 19.829 100 75 150 0 2083147968 34081984 22819792
14:46:14 4614 8192000000 10996808752 11417423872 2014028256 19.733 99 76 152 1 2076024576 39814528 22181792
14:47:27 2296 8192000000 11018324944 11435417600 2028739216 19.849 100 82 162 1 2088318000 35819184 23759600
14:48:33 7769 8192000000 10977174432 11417886720 1996821840 19.598 99 52 103 1 2052962336 39245680 16894816
14:49:54 4727 8192000000 10979047216 11407618048 1997226896 19.601 100 64 127 0 2061076848 43760464 20089488
14:51:06 6031 8192000000 10980395264 11399585792 1989217808 19.538 100 77 154 1 2063167888 49063344 24886736
14:52:16 10641 8192000000 10951367024 11393703936 1960755104 19.312 99 41 82 1 2023991440 49193104 14043232
14:53:32 10029 8192000000 10938718304 11371438080 1952074992 19.243 99 44 88 0 2000189712 34016256 14098464
14:54:47 4602 8192000000 10906819792 11340447744 1923726720 19.017 98 46 91 4 1975884352 37910640 14246992
14:55:53 36527 8192000000 10917350592 11365011456 1921161904 18.996 99 28 56 1 1978629088 47142464 10324720
14:58:06 20609 8192000000 10886158096 11342028800 1909890608 18.906 99 25 50 1 1962751872 44548512 8312752
14:59:44 49989 8192000000 10855887104 11308355584 1841522784 18.353 96 13 26 1 1860425984 15300464 3602736
15:01:35 26925 8192000000 10865697584 11329335296 1923524992 19.015 99 15 30 0 1938189600 10320624 4343984
15:03:25 46842 8192000000 10886794096 11339902976 1941567296 19.159 99 34 68 2 1960776672 9442992 9766384
15:05:12 19025 8192000000 10881352048 11335282688 1946501952 19.199 100 42 83 1 1967902064 9176656 12223456
15:07:38 26867 8192000000 10898980096 11343790080 1950201488 19.228 100 41 77 2 1969617360 8872736 10543136
15:09:10 119019 8192000000 10865882816 11322376192 1877787152 18.647 96 18 35 1 1893559728 10778784 4993792
15:12:15 45239 8192000000 10901873152 11332681728 1926958336 19.043 99 55 110 0 1954575168 12013488 15603344
15:14:14 31376 8192000000 10858035568 11323928576 1939729824 19.145 100 14 28 0 1955022496 10723552 4569120
15:16:50 26451 8192000000 10859944352 11318931456 1940314752 19.149 100 24 47 1 1954316544 7612800 6388992
15:18:17 65250 8192000000 10871728464 11337469952 1949572960 19.223 100 16 32 0 1962966752 8525664 4868128
15:20:24 40443 8192000000 10862787312 11340345344 1954520640 19.262 100 23 47 1 1969156528 8185248 6450640
15:22:08 70029 8192000000 10867616256 11347140608 1939281792 19.141 99 13 26 1 1952938544 9692448 3964304
15:24:19 25300 8192000000 10897067232 11355475968 1957871792 19.289 99 39 78 4 1975977072 7209488 10895792
15:25:57 31806 8192000000 10909465744 11365085184 1959053728 19.299 99 22 44 1 1972040224 6871056 6115440
15:27:30 104850 8192000000 10911574304 11384229888 1964672400 19.343 99 18 36 2 1979163600 9622256 4868944
15:30:15 44474 8192000000 10905935376 11361005568 1909753680 18.905 96 20 38 2 1927523328 12277456 5492192
15:32:00 53648 8192000000 10895606544 11353948160 1927724880 19.049 98 17 33 0 1948124048 16097824 4301344
15:33:57 144103 8192000000 10895721904 11364327424 1847290352 18.400 94 7 14 0 1863942896 15351200 1301344
15:37:24 84905 8192000000 10858912512 11293298688 1782165520 17.867 91 40 79 2 1801264384 7350128 11748736
15:39:52 19425 8192000000 10875876496 11321479168 1923449024 19.014 100 39 78 2 1944215472 10204192 10562256
15:41:11 49786 8192000000 10844481728 11311853568 1913604880 18.936 99 11 22 0 1924215424 6939408 3671136
15:43:02 60474 8192000000 10838942080 11316531200 1897931488 18.810 98 9 18 1 1910019040 9472032 2615520
15:45:02 133066 8192000000 10862586608 11335229440 1889134960 18.739 97 11 21 0 1906114448 14020784 2958704
15:48:16 51997 8192000000 10845574192 11316584448 1870697680 18.590 97 13 24 1 1883176048 9520240 2958128
15:50:10 53950 8192000000 10884175200 11338481664 1930074672 19.067 99 19 38 0 1947478336 12182480 5221184
15:52:26 103825 8192000000 10847150992 11310690304 1847603616 18.403 95 15 30 2 1864456432 12365424 4487392
15:55:59 29865 8192000000 10863875568 11312488448 1907362160 18.885 99 32 64 2 1927458272 11239200 8856912
15:57:32 56222 8192000000 10862023936 11321430016 1900898640 18.834 98 16 32 0 1920788896 15634656 4255600
15:59:39 142307 8192000000 10836717248 11303211008 1852540672 18.443 96 19 38 0 1871237136 13776976 4919488
16:03:07 43238 8192000000 10853505680 11321401344 1899246480 18.820 97 28 56 0 1915144848 8314272 7584096
16:05:04 62624 8192000000 10888834272 11357728768 1942754048 19.169 99 25 47 2 1960558672 11233616 6571008
16:07:18 54889 8192000000 10884066576 11369234432 1963157680 19.331 100 8 16 0 1984211616 18385248 2668688
16:09:18 35078 8192000000 10922123728 11382665216 1991204080 19.553 100 34 68 3 2010381600 9115184 10062336
16:10:54 48237 8192000000 10897047680 11363762176 1910583536 18.911 96 24 48 0 1931017728 13188624 7245568
16:13:26 43837 8192000000 10925896656 11381776384 1967701552 19.367 99 31 61 2 1988778624 12053408 9023664
16:15:11 126228 8192000000 10920711392 11387789312 1919089952 18.980 96 18 36 1 1939049056 14964464 4994640
16:19:03 89714 8192000000 10850407280 11319136256 1886966704 18.721 95 18 36 1 1900773424 8571568 5235152
16:21:34 46265 8192000000 10861756224 11338354688 1915610224 18.952 98 8 16 0 1925681440 7856976 2214240
16:23:21 26049 8192000000 10836086304 11304853504 1888208048 18.731 97 21 42 1 1901392416 6735600 6448768
16:24:50 67871 8192000000 10845979120 11316445184 1907268848 18.885 99 10 20 0 1917455776 7147872 3039056
16:26:58 66549 8192000000 10864055264 11343056896 1926973712 19.043 99 14 27 2 1945065312 13615424 4476176
16:29:09 25587 8192000000 10866893328 11319156736 1866681504 18.557 96 28 56 1 1887101552 12419360 8000688
16:30:35 24415 8192000000 9076777968 9289629696 245018064 2.904 13 3 5 0 249955696 4165008 772624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-10-2020 16:32:10 to 20-10-2020 16:32:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 155 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2020 16:32:26 to 20-10-2020 16:32:28.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242731
MON$OLDEST_ACTIVE               242732
MON$OLDEST_SNAPSHOT             242732
MON$NEXT_TRANSACTION            242732
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2013120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33376 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33376 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33376 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33376 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Oct 20 16:31:11 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

20.10.20 16:32:29. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-10-20 16:32:31 No severe PSQL-related problems occured
20.10.20 16:32:33. End of report.