Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.04.2022 07: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16552636 kB
MemAvailable 31509604 kB
Buffers 0 kB
Cached 15119544 kB
SwapCached 2360 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.2
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 2022-04-09 07:37:08
DTS_END                         2022-04-09 07:37:08.9770
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/42010
TRN_ID                          203934
ATT_ID                          563
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: 8440.66 27537 1012879
customer order: creation 157.17 505 18860
customer order: refuse 13.64 458 1637
order to supplier: creation 41.93 1373 5031
order to supplier: removal 3.17 188451 380
invoice (draft): creation 41.86 3327 5023
invoice (draft): removal 47.00 1078 5640
invoice accept: apply 38.44 29834 4613
invoice accept: cancel 43.13 1318 5176
customer reserve: creation 3635.08 277 436209
customer reserve: removal 4152.06 111 498247
realization accept: apply 75.38 306 9046
realization accept: cancel 72.43 182 8692
payment from customer: creation 55.15 181 6618
payment from customer: removal 5.07 135 608
payment to supplier: creation 44.09 206 5291
payment to supplier: removal 3.12 144 374
service: total inventory turnovers 3.56 5036 427
service: total monetary turnovers 7.36 454 883
service: aggregate perf. data 1.00 14413 120
service: refresh index statistics 0.03 302951 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-04-2022 07:37:57 to 09-04-2022 07:37:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7671
TEST_TIME 2 5047
TEST_TIME 3 5063
TEST_TIME 4 5302
TEST_TIME 5 5746
TEST_TIME 6 5620
TEST_TIME 7 5471
TEST_TIME 8 5348
TEST_TIME 9 5389
TEST_TIME 10 5796
TEST_TIME 11 5973
TEST_TIME 12 6066
TEST_TIME 13 6030
TEST_TIME 14 6241
TEST_TIME 15 6260
TEST_TIME 16 6526
TEST_TIME 17 6512
TEST_TIME 18 6744
TEST_TIME 19 6789
TEST_TIME 20 6852
TEST_TIME 21 6968
TEST_TIME 22 7144
TEST_TIME 23 7188
TEST_TIME 24 7251
TEST_TIME 25 7261
TEST_TIME 26 7875
TEST_TIME 27 8245
TEST_TIME 28 8365
TEST_TIME 29 8760
TEST_TIME 30 9192
TEST_TIME 31 9515
TEST_TIME 32 9515
TEST_TIME 33 9641
TEST_TIME 34 10116
TEST_TIME 35 10320
TEST_TIME 36 10200
TEST_TIME 37 10014
TEST_TIME 38 9968
TEST_TIME 39 9902
TEST_TIME 40 9762
TEST_TIME 41 9823
TEST_TIME 42 9909
TEST_TIME 43 9703
TEST_TIME 44 9580
TEST_TIME 45 9613
TEST_TIME 46 9455
TEST_TIME 47 9534
TEST_TIME 48 9480
TEST_TIME 49 9396
TEST_TIME 50 9321
TEST_TIME 51 9194
TEST_TIME 52 9224
TEST_TIME 53 9152
TEST_TIME 54 9159
TEST_TIME 55 9138
TEST_TIME 56 9037
TEST_TIME 57 8942
TEST_TIME 58 8918
TEST_TIME 59 8796
TEST_TIME 60 8954
TEST_TIME 61 8992
TEST_TIME 62 8955
TEST_TIME 63 8966
TEST_TIME 64 8936
TEST_TIME 65 9015
TEST_TIME 66 8998
TEST_TIME 67 8940
TEST_TIME 68 8905
TEST_TIME 69 8958
TEST_TIME 70 9050
TEST_TIME 71 9008
TEST_TIME 72 8978
TEST_TIME 73 8998
TEST_TIME 74 8970
TEST_TIME 75 8952
TEST_TIME 76 8940
TEST_TIME 77 8842
TEST_TIME 78 8787
TEST_TIME 79 8856
TEST_TIME 80 8810
TEST_TIME 81 8751
TEST_TIME 82 8706
TEST_TIME 83 8652
TEST_TIME 84 8636
TEST_TIME 85 8563
TEST_TIME 86 8595
TEST_TIME 87 8623
TEST_TIME 88 8643
TEST_TIME 89 8613
TEST_TIME 90 8543
TEST_TIME 91 8540
TEST_TIME 92 8511
TEST_TIME 93 8492
TEST_TIME 94 8505
TEST_TIME 95 8510
TEST_TIME 96 8473
TEST_TIME 97 8497
TEST_TIME 98 8525
TEST_TIME 99 8554
TEST_TIME 100 8587
TEST_TIME 101 8646
TEST_TIME 102 8710
TEST_TIME 103 8665
TEST_TIME 104 8654
TEST_TIME 105 8614
TEST_TIME 106 8589
TEST_TIME 107 8580
TEST_TIME 108 8549
TEST_TIME 109 8499
TEST_TIME 110 8471
TEST_TIME 111 8473
TEST_TIME 112 8453
TEST_TIME 113 8451
TEST_TIME 114 8460
TEST_TIME 115 8456
TEST_TIME 116 8449
TEST_TIME 117 8440
TEST_TIME 118 8430
TEST_TIME 119 8544
TEST_TIME 120 8478

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-04-2022 07:38:00 to 09-04-2022 07:38: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 1412467 1412467 0 0.00 0 22827 15 0 0
doc_list_biud 1412457 1412457 0 0.00 0 101455 46 0 0
sp_add_invoice_to_stock 4725 4613 112 2.37 43 209989 29834 0 112
sp_cancel_adding_invoice 5176 5176 0 0.00 3 64876 1319 0 0
sp_cancel_client_order 1637 1637 0 0.00 6 11410 458 0 0
sp_cancel_customer_reserve 498247 498247 0 0.00 1 41030 112 0 0
sp_cancel_pay_from_customer 608 608 0 0.00 3 3613 136 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 4 6596 145 0 0
sp_cancel_supplier_invoice 5647 5640 7 0.12 2 101460 1079 0 7
sp_cancel_supplier_order 380 380 0 0.00 10 1481107 188451 0 0
sp_cancel_write_off 8692 8692 0 0.00 2 12433 183 0 0
sp_client_order 18860 18860 0 0.00 6 18797 506 0 0
sp_customer_reserve 436209 436209 0 0.00 1 44397 278 0 0
sp_fill_shopping_cart 28904 28904 0 0.00 1 18373 90 0 0
sp_fill_shopping_cart_clo_res 436176 436176 0 0.00 0 19900 23 0 0
sp_get_clo_for_invoice 4567 4567 0 0.00 0 43584 458 0 0
sp_kill_cost_storno 14850 14850 0 0.00 0 22812 93 0 0
sp_kill_qstorno_ret_qs2qd 1011206 1011206 0 0.00 0 101453 49 0 0
sp_kill_qty_storno 533384 533384 0 0.00 0 101454 112 0 0
sp_lock_selected_doc 18751 18751 0 0.00 0 9477 6 0 0
sp_make_cost_storno 25522 25522 0 0.00 0 16579 84 0 0
sp_multiply_rows_for_pdistr 25521 25521 0 0.00 0 14061 44 0 0
sp_multiply_rows_for_qdistr 18858 18858 0 0.00 0 15570 195 0 0
sp_pay_from_customer 6618 6618 0 0.00 6 37697 182 0 0
sp_pay_to_supplier 5291 5291 0 0.00 6 16108 207 0 0
sp_payment_common 11908 11908 0 0.00 2 37649 47 0 0
sp_qd_handle_on_cancel_clo 1637 1637 0 0.00 0 11388 373 0 0
sp_qd_handle_on_invoice_upd_sts 9743 9743 0 0.00 0 31591 610 0 0
sp_qd_handle_on_reserve_upd_sts 9045 9045 0 0.00 0 12565 67 0 0
sp_reserve_write_off 9046 9046 0 0.00 9 23137 306 0 0
sp_supplier_invoice 5023 5023 0 0.00 11 76701 3328 0 0
sp_supplier_order 5031 5031 0 0.00 7 28426 1374 0 0
srv_aggregate_perf_data 120 120 0 0.00 2597 242308 14414 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 818 180659 35360 0 0
srv_find_qd_qs_mism 966490 966490 0 0.00 0 15381 12 0 0
srv_make_invnt_saldo 427 427 0 0.00 35 97853 5037 0 0
srv_make_money_saldo 883 883 0 0.00 1 14131 454 0 0
srv_recalc_idx_stat 4 4 0 0.00 113504 437989 302952 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 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 4623 4623 0 0.00 1 19598 1065 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 1 6159 388 0 0
t$perf-norm:sp_cancel_client_order 1637 1637 0 0.00 0 7497 39 0 0
t$perf-norm:sp_cancel_customer_reserve 585 585 0 0.00 0 2509 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 608 608 0 0.00 0 1382 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 1190 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 347 347 0 0.00 0 9863 65 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 111926 8135 0 0
t$perf-norm:sp_cancel_write_off 852 852 0 0.00 0 439 18 0 0
t$perf-norm:sp_client_order 18860 18860 0 0.00 0 8118 42 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 2116 17 0 0
t$perf-norm:sp_pay_from_customer 6618 6618 0 0.00 0 9027 26 0 0
t$perf-norm:sp_pay_to_supplier 5291 5291 0 0.00 0 6871 21 0 0
t$perf-norm:sp_reserve_write_off 9046 9046 0 0.00 0 6609 26 0 0
t$perf-norm:sp_supplier_invoice 5023 5023 0 0.00 0 6918 34 0 0
t$perf-norm:sp_supplier_order 5031 5031 0 0.00 0 6189 37 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 439 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 65 5 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 5702 32 0 0
t$perf-norm:srv_make_money_saldo 883 883 0 0.00 0 862 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 107 45 0 0
v_add_invoice_to_stock 9348 9124 224 2.39 0 7709 28 0 224
v_all_customers 34780 34780 0 0.00 0 124 4 0 0
v_all_suppliers 27857 27857 0 0.00 0 63 4 0 0
v_all_wares 37710 37710 0 0.00 0 53 4 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 10080 30 0 0
v_cancel_client_order 3274 3274 0 0.00 0 8564 15 0 0
v_cancel_customer_prepayment 1216 1216 0 0.00 0 2167 14 0 0
v_cancel_customer_reserve 1170 1170 0 0.00 0 7369 18 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 6518 23 0 0
v_cancel_supplier_invoice 708 694 14 1.97 0 693 23 0 14
v_cancel_supplier_order 760 760 0 0.00 0 6341 18 0 0
v_cancel_write_off 1704 1704 0 0.00 0 5165 28 0 0
v_max_id_clo_ord 5029 5029 0 0.00 0 3952 5 0 0
v_max_id_clo_res 939 939 0 0.00 0 1259 13 0 0
v_max_id_ord_sup 5017 5017 0 0.00 0 4495 7 0 0
v_max_non_paid_invoice 1408 1408 0 0.00 0 431 7 0 0
v_max_non_paid_realizn 3296 3296 0 0.00 0 1810 8 0 0
v_min_id_clo_ord 5029 5029 0 0.00 0 3820 10 0 0
v_min_id_clo_res 939 939 0 0.00 0 7086 17 0 0
v_min_id_ord_sup 5017 5017 0 0.00 0 3016 9 0 0
v_min_non_paid_invoice 5291 5291 0 0.00 0 8017 16 0 0
v_min_non_paid_realizn 6617 6617 0 0.00 0 37641 21 0 0
v_our_firm 9568 9568 0 0.00 0 63 5 0 0
v_reserve_write_off 18086 18086 0 0.00 0 7942 13 0 0
x_lock_depdocs_on_canc_invoice 10469 10469 0 0.00 0 41397 151 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 7976 434 0 0
x_make_qstorno_1000_1200 5031 5031 0 0.00 3 28353 1191 0 0
x_make_qstorno_1200_2000 5023 5023 0 0.00 6 76688 3076 0 0
x_make_qstorno_2100_3300 436209 436209 0 0.00 0 44394 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-04-2022 07:38:05 to 09-04-2022 07:38:09.

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
05:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:14 34982 8192000000 9732015152 10033995776 804041344 8.937 100 87 174 1 834027744 8115168 21871232
05:10:01 17904 8192000000 9777155152 10087825408 846462160 9.365 100 91 182 0 881530336 13370736 21697440
05:11:31 17047 8192000000 9813938192 10139811840 878347584 9.683 100 80 160 0 928034400 31271744 18415072
05:12:53 4883 8192000000 9879741632 10215665664 925760528 10.153 100 86 169 1 995785168 50709872 19314768
05:14:00 4585 8192000000 9935601744 10281799680 971222688 10.599 100 87 171 0 1047705568 58879728 17603152
05:15:09 3602 8192000000 9960525744 10317885440 1007566800 10.952 100 90 180 0 1077503088 52168640 17767648
05:16:16 1809 8192000000 10013904656 10379452416 1021150768 11.083 100 90 170 0 1132656352 94370720 17134864
05:17:20 914 8192000000 10007880624 10378207232 1039490080 11.260 100 90 176 1 1130926224 73005440 18430704
05:18:23 1006 8192000000 10012722592 10385846272 1056165232 11.420 100 90 172 0 1131117280 56804672 18147376
05:19:28 1498 8192000000 10038685328 10414747648 1062050288 11.476 100 93 180 1 1158619504 77705264 18863952
05:20:33 1189 8192000000 10034452400 10410897408 1067460208 11.528 100 93 179 0 1157533056 72742512 17330336
05:21:35 1251 8192000000 10042360176 10421256192 1075779200 11.607 100 94 182 1 1161231376 68094064 17358112
05:22:39 6287 8192000000 10049922096 10428952576 1079749632 11.645 100 84 159 0 1176334640 81598304 14986704
05:23:47 1495 8192000000 10056982256 10440159232 1084066144 11.686 100 94 183 3 1173985824 70120272 19799408
05:24:51 1188 8192000000 10053462256 10439794688 1089527296 11.738 100 94 183 4 1175497728 67553744 18416688
05:25:54 1088 8192000000 10056036544 10445516800 1095190352 11.792 100 96 184 2 1178362416 64959904 18212160
05:26:58 1284 8192000000 10063729680 10449408000 1090790832 11.750 100 95 183 0 1181877184 72530288 18556064
05:28:02 1200 8192000000 10054480864 10442457088 1099973984 11.837 100 89 169 1 1179274272 62525264 16775024
05:29:04 1750 8192000000 10049085616 10438258688 1098448656 11.823 100 98 189 3 1178465312 62108208 17908448
05:30:06 1171 8192000000 10065757296 10454347776 1092917792 11.770 100 95 185 0 1185318608 73766768 18634048
05:31:09 1351 8192000000 10034376080 10419904512 1073372976 11.584 99 95 182 1 1155680848 64829200 17478672
05:32:11 1147 8192000000 10047711520 10426552320 1065788880 11.512 100 91 172 2 1167973760 84300240 17884640
05:33:14 1215 8192000000 10033083056 10410467328 1063104656 11.486 100 96 189 0 1149752688 66458608 20189424
05:34:16 1052 8192000000 10002175360 10378993664 1039910096 11.264 99 83 161 0 1126661792 68282464 18469232
05:35:21 1525 8192000000 10005545680 10379366400 1043463888 11.298 99 97 174 4 1128722720 66118144 19140688
05:36:24 1211 8192000000 9991808080 10364391424 1026779312 11.137 99 94 176 4 1116576816 70156816 19640688
05:37:32 27196 8192000000 9974570240 10353242112 1041417792 11.278 99 62 120 0 1105957456 52882624 11657040
05:39:10 5814 8192000000 10051227744 10433638400 1087550992 11.719 100 76 150 1 1168324224 65786448 14986784
05:40:21 9734 8192000000 10078760432 10467840000 1091302928 11.755 100 68 132 1 1193737504 88998304 13436272
05:41:46 1013 8192000000 10111702176 10493353984 1113441648 11.965 100 97 189 3 1230777264 97866128 19469488
05:42:52 1691 8192000000 10145861984 10532569088 1120228736 12.029 100 87 165 1 1262224464 124439264 17556464
05:43:57 2887 8192000000 10153858384 10560094208 1124455680 12.069 100 66 126 0 1277786640 139213648 14117312
05:45:05 7273 8192000000 10195038112 10595770368 1133559968 12.155 100 61 118 0 1313903504 167769664 12573872
05:46:17 2995 8192000000 10230519296 10628206592 1144032672 12.253 100 81 158 0 1344716224 182570816 18112736
05:47:27 3880 8192000000 10233127056 10625445888 1144721680 12.260 99 88 170 1 1342076048 179097104 18257264
05:48:34 1549 8192000000 10285342448 10683539456 1161275840 12.415 100 93 181 0 1402798752 220479136 21043776
05:49:40 2087 8192000000 10299495008 10703384576 1171925952 12.515 100 70 138 1 1417744896 230689424 15129520
05:50:46 949 8192000000 10354809504 10764394496 1181154128 12.601 100 96 188 0 1466142336 263618112 21370096
05:51:49 1341 8192000000 10389281136 10797740032 1184903872 12.636 100 88 168 1 1495029600 291583008 18542720
05:52:56 1756 8192000000 10406563232 10831814656 1192257680 12.704 100 95 180 0 1509445216 296182800 21004736
05:54:04 1016 8192000000 10442548704 10850611200 1201654720 12.792 100 99 193 0 1550201408 325398832 23147856
05:55:11 1019 8192000000 10478961232 10902847488 1213776896 12.904 100 96 190 0 1586725808 349483424 23465488
05:56:14 1672 8192000000 10520488000 10928201728 1218344384 12.946 100 100 197 0 1627857168 387863536 21649248
05:57:20 1107 8192000000 10557305200 10975416320 1208444432 12.855 100 93 185 2 1665847520 434696240 22706848
05:58:28 1369 8192000000 10556219152 11005816832 1203617568 12.810 100 91 181 0 1660003920 432939744 23446608
05:59:32 1053 8192000000 10570918944 10997682176 1200023520 12.777 100 95 188 1 1674509312 451790800 22694992
06:00:37 1370 8192000000 10598370240 11035799552 1202064912 12.796 100 89 178 0 1707777984 483689872 22023200
06:01:41 1236 8192000000 10587550432 11046289408 1206165472 12.834 100 96 190 2 1693608784 462631936 24811376
06:02:45 807 8192000000 10658873296 11128500224 1213889904 12.905 100 97 194 0 1763290176 525767312 23632960
06:03:50 8715 8192000000 10561883632 11063320576 1210982176 12.878 100 63 124 1 1695800000 468979120 15838704
06:05:01 10894 8192000000 10545982416 11007602688 1187115024 12.657 100 43 86 0 1673415040 474169728 12130288
06:06:43 23376 8192000000 10446953216 10967252992 1183852240 12.626 100 44 86 1 1551932400 356934352 11145808
06:08:14 34132 8192000000 10326970384 10853240832 1159324464 12.397 100 17 34 1 1428858320 265450496 4083360
06:09:51 22182 8192000000 10292439008 10757279744 1146617856 12.278 99 28 56 0 1405161104 251464704 7078544
06:11:15 2675 8192000000 10203996528 10672852992 1151678208 12.325 100 80 160 2 1302538672 133494544 17365920
06:12:20 22805 8192000000 10140835504 10582159360 1127851328 12.101 98 25 50 0 1236239120 102619920 5767872
06:14:02 4595 8192000000 10141180352 10570911744 1152902112 12.337 100 59 113 4 1240006896 74677808 12426976
06:15:18 13910 8192000000 10178506736 10634694656 1150686144 12.316 100 37 71 0 1289783616 131722912 7374560
06:16:33 103388 8192000000 10201264800 10664488960 1133613872 12.155 99 19 38 1 1302838496 164662832 4561792
06:19:37 180658 8192000000 10084360656 10573488128 1115353264 11.983 98 23 46 0 1201040528 80768896 4918368
06:23:43 102169 8192000000 10109964176 10556874752 1151185360 12.321 100 28 55 0 1212911520 56252608 5473552
06:26:26 73972 8192000000 10097168672 10557788160 1146353712 12.275 100 14 28 1 1204987728 55564816 3069200
06:28:42 3672 8192000000 10128576384 10555576320 1143507920 12.249 99 54 107 1 1224067264 69736608 10822736
06:29:51 49500 8192000000 10149840096 10596880384 1158388048 12.388 100 37 72 0 1240425648 73743168 8294432
06:31:43 74410 8192000000 10155388704 10635681792 1148222304 12.293 99 17 34 0 1259192096 107187088 3782704
06:35:09 118256 8192000000 10168691104 10646773760 1144842400 12.261 99 22 44 2 1283357200 133338848 5175952
06:39:07 62495 8192000000 10118163360 10614468608 1144644640 12.259 99 7 14 0 1234226528 88082720 1499168
06:41:11 87321 8192000000 10092144784 10578903040 1120248992 12.029 97 31 61 0 1159262208 32609408 6403808
06:43:47 32212 8192000000 10103297312 10536988672 1156225856 12.368 100 50 97 0 1213832560 46801040 10805664
06:45:19 82372 8192000000 10122942800 10598989824 1139507232 12.211 98 24 48 0 1237760960 93225328 5028400
06:47:50 88177 8192000000 10094018656 10542424064 1117710688 12.005 97 16 32 0 1150186256 29119760 3355808
06:50:18 88195 8192000000 10101435104 10537013248 1066888480 11.522 93 31 62 0 1113061104 40234400 5938224
06:52:57 52725 8192000000 10149130000 10608279552 1115296272 11.983 98 28 56 0 1223909072 102492624 6120176
06:55:35 21690 8192000000 10100355680 10521817088 1105831520 11.893 98 57 107 1 1192813328 74398608 12583200
06:56:59 50104 8192000000 10023101744 10473766912 1111482240 11.946 100 21 42 1 1143355248 27237840 4635168
06:58:53 79354 8192000000 10065104048 10509537280 1088874288 11.732 97 25 50 2 1126805120 32456448 5474384
07:01:51 69161 8192000000 10103968336 10576044032 1112099232 11.952 99 17 34 2 1207154960 90838880 4216848
07:04:36 47194 8192000000 10045153216 10535768064 1093652512 11.777 98 30 55 2 1143899760 44578352 5668896
07:06:23 106504 8192000000 10036992240 10488279040 1069646912 11.549 96 15 30 0 1111247152 38639744 2960496
07:09:21 66935 8192000000 10051693728 10508873728 1113063216 11.961 99 23 41 2 1153582400 36465040 4054144
07:11:30 39570 8192000000 10070735568 10533818368 1116621920 11.995 100 20 40 0 1173693120 52981312 4089888
07:13:23 17882 8192000000 10137256480 10573582336 1123163712 12.057 99 39 78 0 1213711168 82415984 8131472
07:14:41 46555 8192000000 10154569056 10620178432 1119866304 12.026 99 42 84 1 1259473136 130809312 8797520
07:16:32 22675 8192000000 10143615792 10571276288 1138894032 12.205 100 30 60 0 1243898704 98464496 6540176
07:17:56 51793 8192000000 10122750560 10635014144 1131478272 12.135 99 32 62 2 1273275664 135169824 6627568
07:19:49 47656 8192000000 10085749232 10503348224 1131259296 12.133 99 39 74 0 1167044576 28035312 7749968
07:21:41 46946 8192000000 10111113760 10536890368 1134548688 12.164 100 64 128 2 1210300704 63172048 12579968
07:24:03 34824 8192000000 10061678256 10524848128 1117111776 12.000 99 19 38 2 1156978480 36040576 3826128
07:25:41 53770 8192000000 10043059776 10499674112 1124458400 12.069 100 11 22 0 1154562080 27809264 2294416
07:27:36 60861 8192000000 10076022464 10546946048 1132290688 12.143 100 18 36 0 1179951264 43858208 3802368
07:30:34 55647 8192000000 10063151184 10532253696 1129267376 12.114 100 15 30 0 1178423280 45766656 3389248
07:32:31 26566 8192000000 10097405264 10532929536 1128710464 12.109 99 31 62 0 1182245248 47065360 6469424
07:34:02 15480 8192000000 10130850064 10563211264 1126403888 12.087 99 44 83 2 1221743408 87065552 8273968
07:35:18 39583 8192000000 10157930160 10599469056 1094116816 11.782 96 32 64 0 1228195440 127440384 6638240
07:37:05 15016 8192000000 9192247744 9512812544 324208160 3.806 29 2 4 0 348908176 24459952 240064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-04-2022 07:38:11 to 09-04-2022 07:38:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-04-2022 07:38:28 to 09-04-2022 07:38:30.

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          206120
MON$OLDEST_ACTIVE               206121
MON$OLDEST_SNAPSHOT             206121
MON$NEXT_TRANSACTION            206121
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                       2048032
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             748
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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'

09.04.22 07:38:31. 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
2022-04-09 07:38:33 No severe PSQL-related problems occured
09.04.22 07:38:35. End of report.