Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.09.2021 13:37
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 15811216 kB
MemAvailable 30746040 kB
Buffers 0 kB
Cached 15867488 kB
SwapCached 2964 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2021-09-29 13:37:08
DTS_END                         2021-09-29 13:37:08.7680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36304
TRN_ID                          243239
ATT_ID                          669
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: 9372.78 25705 1124734
customer order: creation 174.98 386 20998
customer order: refuse 14.71 324 1765
order to supplier: creation 46.79 1543 5615
order to supplier: removal 3.55 106466 426
invoice (draft): creation 47.28 3593 5673
invoice (draft): removal 54.97 823 6596
invoice accept: apply 42.20 18430 5064
invoice accept: cancel 49.72 881 5966
customer reserve: creation 3809.20 186 457104
customer reserve: removal 4828.17 62 579380
realization accept: apply 85.06 184 10207
realization accept: cancel 81.61 118 9793
payment from customer: creation 61.71 124 7405
payment from customer: removal 6.58 109 789
payment to supplier: creation 51.05 156 6126
payment to supplier: removal 3.74 102 449
service: total inventory turnovers 3.93 3724 471
service: total monetary turnovers 6.59 517 791
service: aggregate perf. data 0.93 16243 112
service: refresh index statistics 0.03 360133 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-09-2021 13:37:45 to 29-09-2021 13:37:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10173
TEST_TIME 2 8566
TEST_TIME 3 7598
TEST_TIME 4 7768
TEST_TIME 5 7076
TEST_TIME 6 6743
TEST_TIME 7 6764
TEST_TIME 8 6613
TEST_TIME 9 6269
TEST_TIME 10 6787
TEST_TIME 11 6989
TEST_TIME 12 7391
TEST_TIME 13 7448
TEST_TIME 14 7312
TEST_TIME 15 7341
TEST_TIME 16 7363
TEST_TIME 17 7833
TEST_TIME 18 7956
TEST_TIME 19 8145
TEST_TIME 20 8619
TEST_TIME 21 8585
TEST_TIME 22 8801
TEST_TIME 23 8890
TEST_TIME 24 9001
TEST_TIME 25 9086
TEST_TIME 26 9156
TEST_TIME 27 9125
TEST_TIME 28 9244
TEST_TIME 29 10071
TEST_TIME 30 10904
TEST_TIME 31 10833
TEST_TIME 32 10818
TEST_TIME 33 10930
TEST_TIME 34 10848
TEST_TIME 35 10546
TEST_TIME 36 10615
TEST_TIME 37 10505
TEST_TIME 38 10468
TEST_TIME 39 10408
TEST_TIME 40 10284
TEST_TIME 41 10202
TEST_TIME 42 10205
TEST_TIME 43 10148
TEST_TIME 44 10041
TEST_TIME 45 9980
TEST_TIME 46 9792
TEST_TIME 47 10033
TEST_TIME 48 10034
TEST_TIME 49 10030
TEST_TIME 50 10040
TEST_TIME 51 10041
TEST_TIME 52 10021
TEST_TIME 53 10125
TEST_TIME 54 10056
TEST_TIME 55 10095
TEST_TIME 56 10145
TEST_TIME 57 10160
TEST_TIME 58 10172
TEST_TIME 59 10021
TEST_TIME 60 9942
TEST_TIME 61 9850
TEST_TIME 62 9815
TEST_TIME 63 9803
TEST_TIME 64 9706
TEST_TIME 65 9646
TEST_TIME 66 9615
TEST_TIME 67 9589
TEST_TIME 68 9661
TEST_TIME 69 9619
TEST_TIME 70 9607
TEST_TIME 71 9572
TEST_TIME 72 9502
TEST_TIME 73 9750
TEST_TIME 74 9729
TEST_TIME 75 9738
TEST_TIME 76 9778
TEST_TIME 77 9735
TEST_TIME 78 9862
TEST_TIME 79 9908
TEST_TIME 80 9869
TEST_TIME 81 9758
TEST_TIME 82 9697
TEST_TIME 83 9690
TEST_TIME 84 9630
TEST_TIME 85 9584
TEST_TIME 86 9528
TEST_TIME 87 9474
TEST_TIME 88 9428
TEST_TIME 89 9492
TEST_TIME 90 9465
TEST_TIME 91 9478
TEST_TIME 92 9483
TEST_TIME 93 9471
TEST_TIME 94 9481
TEST_TIME 95 9440
TEST_TIME 96 9445
TEST_TIME 97 9571
TEST_TIME 98 9579
TEST_TIME 99 9539
TEST_TIME 100 9564
TEST_TIME 101 9585
TEST_TIME 102 9506
TEST_TIME 103 9453
TEST_TIME 104 9427
TEST_TIME 105 9374
TEST_TIME 106 9339
TEST_TIME 107 9373
TEST_TIME 108 9362
TEST_TIME 109 9337
TEST_TIME 110 9350
TEST_TIME 111 9330
TEST_TIME 112 9342
TEST_TIME 113 9316
TEST_TIME 114 9355
TEST_TIME 115 9338
TEST_TIME 116 9379
TEST_TIME 117 9404
TEST_TIME 118 9446
TEST_TIME 119 9435
TEST_TIME 120 9373

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-09-2021 13:37:48 to 29-09-2021 13:37:52.

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 1550250 1550250 0 0.00 0 19069 7 0 0
doc_list_biud 1550250 1550250 0 0.00 0 27731 29 0 0
sp_add_invoice_to_stock 5220 5064 156 2.98 42 191898 18430 0 156
sp_cancel_adding_invoice 5966 5966 0 0.00 4 92221 882 0 0
sp_cancel_client_order 1765 1765 0 0.00 5 27772 324 0 0
sp_cancel_customer_reserve 579380 579380 0 0.00 1 19515 63 0 0
sp_cancel_pay_from_customer 789 789 0 0.00 3 7467 110 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 2 5528 102 0 0
sp_cancel_supplier_invoice 6609 6596 13 0.19 2 23860 823 0 13
sp_cancel_supplier_order 426 426 0 0.00 15 815652 106467 0 0
sp_cancel_write_off 9793 9793 0 0.00 1 12622 119 0 0
sp_client_order 20998 20998 0 0.00 4 20169 387 0 0
sp_customer_reserve 457104 457104 0 0.00 0 50087 187 0 0
sp_fill_shopping_cart 32279 32279 0 0.00 1 17252 44 0 0
sp_fill_shopping_cart_clo_res 457103 457103 0 0.00 0 14610 17 0 0
sp_get_clo_for_invoice 5062 5062 0 0.00 0 27107 323 0 0
sp_kill_cost_storno 16997 16997 0 0.00 0 6913 56 0 0
sp_kill_qstorno_ret_qs2qd 1175575 1175575 0 0.00 0 23848 34 0 0
sp_kill_qty_storno 619195 619195 0 0.00 0 27730 72 0 0
sp_lock_selected_doc 21309 21309 0 0.00 0 9652 6 0 0
sp_make_cost_storno 28800 28800 0 0.00 0 17563 61 0 0
sp_multiply_rows_for_pdistr 28800 28800 0 0.00 0 11935 38 0 0
sp_multiply_rows_for_qdistr 20998 20998 0 0.00 0 18553 189 0 0
sp_pay_from_customer 7405 7405 0 0.00 4 20732 125 0 0
sp_pay_to_supplier 6126 6126 0 0.00 5 17028 157 0 0
sp_payment_common 13531 13531 0 0.00 1 7436 29 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 27730 297 0 0
sp_qd_handle_on_invoice_upd_sts 11028 11028 0 0.00 0 15105 327 0 0
sp_qd_handle_on_reserve_upd_sts 10207 10207 0 0.00 0 10756 51 0 0
sp_reserve_write_off 10207 10207 0 0.00 7 17574 185 0 0
sp_supplier_invoice 5673 5673 0 0.00 8 88913 3593 0 0
sp_supplier_order 5615 5615 0 0.00 5 33147 1543 0 0
srv_aggregate_perf_data 113 112 1 0.88 2848 288543 16244 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1853 125003 43420 0 0
srv_find_qd_qs_mism 1077342 1077342 0 0.00 0 19069 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 54 110722 3724 0 0
srv_make_money_saldo 791 791 0 0.00 1 16974 517 0 0
srv_recalc_idx_stat 4 4 0 0.00 127863 491440 360133 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5064 5064 0 0.00 0 16187 218 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 30411 175 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 5966 19 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 1033 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 789 789 0 0.00 0 2292 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 400 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 382 9 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 21266 1681 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 6608 23 0 0
t$perf-norm:sp_client_order 20998 20998 0 0.00 0 7338 18 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 1678 5 0 0
t$perf-norm:sp_pay_from_customer 7405 7405 0 0.00 0 4874 9 0 0
t$perf-norm:sp_pay_to_supplier 6126 6126 0 0.00 0 5872 10 0 0
t$perf-norm:sp_reserve_write_off 10207 10207 0 0.00 0 11951 10 0 0
t$perf-norm:sp_supplier_invoice 5673 5673 0 0.00 0 11957 22 0 0
t$perf-norm:sp_supplier_order 5615 5615 0 0.00 0 11458 20 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 182 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 224 4 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 664 8 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 8861 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 10436 10124 312 2.98 0 5299 17 0 312
v_all_customers 38414 38414 0 0.00 0 121 1 0 0
v_all_suppliers 31588 31588 0 0.00 0 122 1 0 0
v_all_wares 41992 41992 0 0.00 0 122 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1663 8 0 0
v_cancel_client_order 3530 3530 0 0.00 0 4383 6 0 0
v_cancel_customer_prepayment 1578 1578 0 0.00 0 2727 7 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 1345 7 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 847 6 0 0
v_cancel_supplier_invoice 882 856 26 2.94 0 1370 17 0 26
v_cancel_supplier_order 852 852 0 0.00 0 2036 10 0 0
v_cancel_write_off 2018 2018 0 0.00 0 1421 14 0 0
v_max_id_clo_ord 5614 5614 0 0.00 0 476 2 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 1420 8 0 0
v_max_id_ord_sup 5669 5669 0 0.00 0 16490 7 0 0
v_max_non_paid_invoice 1615 1615 0 0.00 0 186 2 0 0
v_max_non_paid_realizn 3995 3995 0 0.00 0 2268 4 0 0
v_min_id_clo_ord 5614 5614 0 0.00 0 2748 7 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 2846 7 0 0
v_min_id_ord_sup 5669 5669 0 0.00 0 8041 6 0 0
v_min_non_paid_invoice 6126 6126 0 0.00 0 7433 22 0 0
v_min_non_paid_realizn 7405 7405 0 0.00 0 5783 19 0 0
v_our_firm 10398 10398 0 0.00 0 122 1 0 0
v_reserve_write_off 20414 20414 0 0.00 0 4385 6 0 0
x_lock_depdocs_on_canc_invoice 12134 12134 0 0.00 0 24574 109 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 9669 340 0 0
x_make_qstorno_1000_1200 5615 5615 0 0.00 2 33133 1456 0 0
x_make_qstorno_1200_2000 5673 5673 0 0.00 4 88885 3435 0 0
x_make_qstorno_2100_3300 457104 457104 0 0.00 0 46139 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-09-2021 13:37:53 to 29-09-2021 13:37:57.

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
11:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 28646 8192000000 9719514880 10017636352 800806592 8.904 100 82 165 0 828081632 7529872 19745168
11:09:51 12973 8192000000 9768611904 10078248960 847524240 9.375 100 87 174 0 880583776 12488848 20570688
11:11:09 17785 8192000000 9813326416 10133958656 874696032 9.647 100 87 175 0 916185504 21238736 20250736
11:12:31 12663 8192000000 9871046400 10205298688 926991584 10.165 100 82 164 1 985070432 40029600 18049248
11:13:45 7617 8192000000 9916868592 10260492288 951698864 10.408 100 81 160 1 1026100544 58019904 16381776
11:14:55 7196 8192000000 9975725648 10323931136 986262528 10.745 100 86 173 2 1080278544 76448960 17567056
11:16:07 1690 8192000000 9987137376 10343202816 1011236784 10.987 100 91 182 1 1101994112 73097392 17659936
11:17:11 2535 8192000000 10010912576 10380701696 1037755568 11.243 100 92 184 0 1128840784 72968608 18116608
11:18:16 2157 8192000000 10026580256 10396053504 1059543552 11.452 100 96 191 0 1145890080 66963280 19383248
11:19:19 13028 8192000000 9985311328 10375475200 1064436848 11.499 100 45 90 3 1102828864 28497680 9894336
11:20:33 5062 8192000000 10002596448 10395762688 1074123488 11.591 100 53 106 1 1113548032 27269232 12155312
11:21:38 4468 8192000000 10000236432 10391379968 1079348992 11.641 100 46 93 2 1114283552 25935168 8999392
11:22:43 5095 8192000000 9996572864 10387255296 1082865520 11.675 100 34 66 2 1110933728 20460192 7608016
11:23:49 55324 8192000000 10002065616 10420686848 1085655440 11.701 100 26 52 0 1119397136 28270752 5470944
11:25:45 3956 8192000000 10026604512 10424745984 1099490160 11.833 100 59 118 3 1140208576 28863200 11855216
11:26:52 6272 8192000000 10009032192 10405986304 1092418624 11.766 100 55 108 2 1125942336 21215616 12308096
11:27:58 12253 8192000000 9978144160 10384846848 1052169728 11.381 97 32 63 0 1086163680 26866432 7127520
11:29:11 28506 8192000000 9970356368 10359812096 1014868448 11.022 94 41 82 2 1044401600 20770000 8763152
11:30:40 5289 8192000000 9959894688 10335088640 1021043904 11.082 99 31 57 1 1052252176 25168480 6039792
11:31:46 15419 8192000000 9943495264 10334879744 1027431776 11.144 100 25 50 2 1054494464 21611904 5450784
11:33:02 9024 8192000000 9947499664 10338557952 1024522608 11.116 99 29 58 0 1051745312 20773168 6449536
11:34:15 19954 8192000000 9966377152 10363101184 1042373968 11.287 100 20 40 0 1062929616 16869648 3686000
11:35:36 9197 8192000000 9968588128 10367340544 1050799152 11.368 100 46 91 1 1079126992 17894976 10432864
11:36:49 8152 8192000000 9976852032 10387333120 1063398960 11.489 100 29 58 0 1090720704 20545696 6776048
11:38:02 55108 8192000000 10023254784 10452090880 1080438256 11.652 100 48 94 2 1156026784 65843120 9745408
11:40:06 8160 8192000000 10036874336 10440257536 1096920304 11.808 100 63 126 0 1152462624 42990944 12551376
11:41:19 2719 8192000000 10085661680 10481594368 1111473712 11.946 100 71 142 1 1182707232 56136928 15096592
11:42:25 48302 8192000000 10105498144 10519883776 1123818880 12.063 100 53 106 1 1204904672 70432096 10653696
11:44:17 17493 8192000000 10129508880 10548170752 1132614688 12.146 100 39 78 1 1242043088 100950016 8478384
11:45:38 56985 8192000000 10149745360 10577084416 1140092208 12.216 100 40 80 1 1249864864 100200496 9572160
11:47:38 10113 8192000000 10168667392 10603782144 1142433008 12.238 99 59 115 4 1281040496 125784016 12823472
11:49:02 3574 8192000000 10158841440 10589073408 1150431392 12.314 99 65 128 2 1266516976 101471408 14614176
11:50:09 4558 8192000000 10198127408 10615185408 1169441056 12.492 100 65 128 2 1309383104 126159952 13782096
11:51:16 3867 8192000000 10176846480 10591735808 1153907280 12.346 100 46 92 0 1284748912 120838736 10002896
11:52:23 2468 8192000000 10226898208 10625454080 1139295200 12.209 100 72 143 0 1325259200 170450224 15513776
11:53:31 4285 8192000000 10196115040 10604277760 1120278656 12.030 99 67 131 2 1293012848 158260208 14473984
11:54:45 1850 8192000000 10225835904 10624589824 1132574864 12.146 99 90 180 2 1326107200 173587456 19944880
11:55:50 4016 8192000000 10266973440 10671955968 1144972064 12.262 100 80 160 0 1373660160 210046128 18641968
11:56:58 4845 8192000000 10278684080 10685169664 1130570368 12.127 100 68 137 0 1372537728 225689264 16278096
11:58:07 12668 8192000000 10271649680 10691756032 1139011632 12.206 100 42 82 1 1387074448 237834144 10228672
11:59:24 3754 8192000000 10323659824 10737930240 1131029488 12.131 100 49 98 0 1424752944 281741648 11981808
12:00:39 1857 8192000000 10367410096 10767339520 1157411216 12.379 100 83 166 0 1459852368 282856752 19584400
12:01:45 16834 8192000000 10360868896 10783825920 1156690944 12.372 100 35 70 2 1462270752 296552144 9027664
12:03:03 32541 8192000000 10384364176 10861346816 1151809504 12.326 100 29 58 1 1496843616 336928080 8106032
12:04:45 8114 8192000000 10441070976 10891091968 1146735440 12.279 100 31 62 0 1531349648 376437328 8176880
12:06:01 26320 8192000000 10280525296 10800181248 1133647616 12.156 100 22 44 0 1477916880 338107664 6161600
12:07:47 44357 8192000000 10105207616 10561052672 1131069184 12.131 100 12 24 1 1212807344 79098720 2639440
12:09:55 56562 8192000000 10087618000 10523136000 1130177600 12.123 100 15 30 0 1192480528 59030096 3272832
12:12:42 100250 8192000000 10105055504 10571272192 1092642080 11.768 96 33 66 2 1180161968 80302752 7217136
12:15:24 22442 8192000000 10109361328 10580992000 1118575840 12.014 99 31 63 0 1205983504 80966048 6441616
12:16:52 39193 8192000000 10123429216 10554093568 1141710144 12.232 100 34 68 1 1193049888 44258528 7081216
12:18:33 23374 8192000000 10160066384 10609053696 1156816144 12.373 100 36 72 1 1267218912 103015456 7387312
12:20:02 16707 8192000000 10127261984 10634620928 1159758592 12.401 100 13 26 1 1256888880 94332912 2797376
12:21:27 54336 8192000000 10171569536 10626576384 1157389136 12.379 99 24 47 0 1257686976 94644464 5653376
12:23:22 66056 8192000000 10184720752 10642546688 1153993568 12.347 100 32 65 0 1287487776 126227328 7266880
12:25:31 53692 8192000000 10117294864 10580934656 1134513360 12.164 98 24 48 1 1194089072 54406992 5168720
12:27:26 27657 8192000000 10103622528 10559954944 1146567744 12.277 99 36 73 0 1187470576 33333408 7569424
12:28:54 74013 8192000000 10135335392 10595692544 1137831440 12.195 98 21 42 1 1208546768 66237728 4477600
12:31:12 28424 8192000000 10102313568 10542014464 1135984976 12.178 98 25 48 3 1188925360 47413712 5526672
12:32:40 125003 8192000000 10095049104 10603507712 1127272976 12.096 97 22 44 0 1217556720 86005920 4277824
12:35:48 119603 8192000000 10079239776 10533060608 1084590160 11.691 94 30 60 0 1123535728 32917072 6028496
12:39:08 45889 8192000000 10069948720 10526703616 1130042864 12.122 100 22 44 0 1204628752 69696352 4889536
12:41:07 61900 8192000000 10063516496 10517790720 1071627616 11.568 94 9 18 0 1110432160 36859872 1944672
12:43:10 45979 8192000000 10083580080 10560339968 1099402336 11.832 98 10 20 0 1179954704 78130672 2421696
12:45:08 61697 8192000000 10028490144 10469277696 1087979376 11.723 98 11 22 0 1140078496 49779872 2319248
12:47:13 47573 8192000000 10083598256 10537783296 1117787296 12.006 100 34 68 0 1187126912 62347280 6992336
12:49:09 28238 8192000000 10137620512 10571071488 1090727056 11.750 97 40 79 2 1191008944 91855472 8426416
12:50:46 88431 8192000000 10058794192 10503655424 1062304288 11.479 95 16 32 1 1109744112 44267440 3172384
12:53:19 84004 8192000000 10103762336 10533425152 1081152400 11.658 96 17 34 1 1126686112 41890192 3643520
12:55:50 67978 8192000000 10075767072 10603143168 1109310640 11.926 99 10 20 1 1196742000 85554544 1876816
12:57:58 111159 8192000000 10048443952 10500517888 1118555744 12.013 99 29 56 0 1154175792 29480736 6139312
13:00:49 33704 8192000000 10049428096 10502270976 1096275360 11.802 97 14 28 1 1127654912 28640592 2738960
13:02:24 48695 8192000000 10101826096 10586771456 1134762048 12.166 100 13 26 1 1204090528 66065712 3262768
13:04:13 77074 8192000000 10132068080 10581471232 1117207376 12.001 99 22 44 0 1214123472 91956192 4959904
13:06:39 60300 8192000000 10066520976 10553380864 1120322960 12.030 99 15 30 0 1188587680 65438592 2826128
13:08:40 57231 8192000000 10057911776 10533154816 1124670176 12.071 99 20 40 1 1174253744 45450576 4132992
13:10:39 42175 8192000000 10095148832 10540085248 1102543664 11.862 97 42 84 0 1153000416 41453696 9003056
13:12:26 53895 8192000000 10147760160 10622050304 1134647216 12.165 99 21 42 0 1245045392 105370992 5027184
13:14:22 65452 8192000000 10095286784 10553208832 1114392976 11.974 98 17 35 0 1230765888 112416432 3956480
13:16:33 19691 8192000000 10073876848 10506133504 1129428160 12.116 99 39 79 1 1147489520 8928448 9132912
13:17:55 98897 8192000000 10085448864 10570801152 1138353936 12.200 99 23 46 0 1207529088 64275552 4899600
13:20:36 20369 8192000000 10084038592 10536321024 1154091712 12.348 100 38 76 3 1179454016 17087552 8274752
13:21:56 65681 8192000000 10084692752 10567581696 1143939584 12.253 100 11 22 0 1208079120 61861296 2278240
13:24:09 64322 8192000000 10099004912 10592681984 1150219040 12.312 99 11 22 0 1224703136 72447168 2036928
13:26:17 21455 8192000000 10062140544 10494918656 1083605232 11.682 95 30 58 1 1107455456 17687632 6162592
13:27:52 110913 8192000000 10066505920 10548101120 1077488608 11.624 95 16 32 0 1120176544 39436752 3251184
13:30:45 49971 8192000000 10099850160 10573484032 1104375472 11.879 98 29 58 0 1163288928 53195632 5717824
13:32:39 71605 8192000000 10123039360 10600587264 1101506304 11.852 97 33 66 0 1187404320 78604560 7293456
13:34:52 42619 8192000000 10066597456 10538217472 1116522144 11.994 98 28 56 0 1160285936 37909584 5854208
13:36:36 36649 8192000000 9033287600 9243901952 183936144 2.196 17 3 6 0 196716368 12331472 448752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-09-2021 13:37:59 to 29-09-2021 13:38:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 156 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-09-2021 13:38:15 to 29-09-2021 13:38:17.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243586
MON$OLDEST_ACTIVE               243587
MON$OLDEST_SNAPSHOT             243587
MON$NEXT_TRANSACTION            243587
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-30 07:30:08.6030 GMT
MON$PAGES                       2059232
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             814
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
--- end of diff output ---

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

29.09.21 13:38:18. There are 3 logs in /var/tmp/logs-oltp40 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
2021-09-29 13:38:21 No severe PSQL-related problems occured
29.09.21 13:38:22. End of report.