Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.07.2022 04:38
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 15407732 kB
MemAvailable 31069288 kB
Buffers 20 kB
Cached 16203048 kB
SwapCached 2536 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-07-09 04:37:17
DTS_END                         2022-07-09 04:37:17.8600
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/45598
TRN_ID                          209057
ATT_ID                          583
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: 8551.12 30322 1026134
customer order: creation 159.06 529 19087
customer order: refuse 13.43 475 1612
order to supplier: creation 41.55 1469 4986
order to supplier: removal 3.22 195755 386
invoice (draft): creation 42.08 3579 5050
invoice (draft): removal 47.31 1145 5677
invoice accept: apply 39.08 26539 4690
invoice accept: cancel 42.40 1499 5088
customer reserve: creation 3675.02 244 441002
customer reserve: removal 4215.50 116 505860
realization accept: apply 78.13 327 9375
realization accept: cancel 73.31 199 8797
payment from customer: creation 55.69 201 6683
payment from customer: removal 5.63 165 675
payment to supplier: creation 44.63 233 5355
payment to supplier: removal 3.20 209 384
service: total inventory turnovers 3.68 4622 442
service: total monetary turnovers 7.34 357 881
service: aggregate perf. data 0.83 20146 100
service: refresh index statistics 0.03 348633 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-07-2022 04:38:28 to 09-07-2022 04:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5454
TEST_TIME 2 4843
TEST_TIME 3 4423
TEST_TIME 4 4865
TEST_TIME 5 5371
TEST_TIME 6 5700
TEST_TIME 7 6257
TEST_TIME 8 6590
TEST_TIME 9 6736
TEST_TIME 10 6735
TEST_TIME 11 6768
TEST_TIME 12 6916
TEST_TIME 13 7253
TEST_TIME 14 7307
TEST_TIME 15 7356
TEST_TIME 16 7417
TEST_TIME 17 7300
TEST_TIME 18 7268
TEST_TIME 19 7150
TEST_TIME 20 7110
TEST_TIME 21 7091
TEST_TIME 22 7418
TEST_TIME 23 7386
TEST_TIME 24 7662
TEST_TIME 25 7624
TEST_TIME 26 7793
TEST_TIME 27 8085
TEST_TIME 28 8519
TEST_TIME 29 8882
TEST_TIME 30 9201
TEST_TIME 31 9298
TEST_TIME 32 9480
TEST_TIME 33 9805
TEST_TIME 34 9974
TEST_TIME 35 10047
TEST_TIME 36 10281
TEST_TIME 37 10823
TEST_TIME 38 10844
TEST_TIME 39 10737
TEST_TIME 40 10477
TEST_TIME 41 10429
TEST_TIME 42 10527
TEST_TIME 43 10662
TEST_TIME 44 10614
TEST_TIME 45 10434
TEST_TIME 46 10287
TEST_TIME 47 10171
TEST_TIME 48 10067
TEST_TIME 49 9990
TEST_TIME 50 9979
TEST_TIME 51 9825
TEST_TIME 52 9725
TEST_TIME 53 9681
TEST_TIME 54 9682
TEST_TIME 55 9697
TEST_TIME 56 9764
TEST_TIME 57 9651
TEST_TIME 58 9527
TEST_TIME 59 9452
TEST_TIME 60 9379
TEST_TIME 61 9370
TEST_TIME 62 9355
TEST_TIME 63 9292
TEST_TIME 64 9235
TEST_TIME 65 9170
TEST_TIME 66 9113
TEST_TIME 67 8989
TEST_TIME 68 8909
TEST_TIME 69 8906
TEST_TIME 70 8887
TEST_TIME 71 8823
TEST_TIME 72 8832
TEST_TIME 73 8857
TEST_TIME 74 8906
TEST_TIME 75 8849
TEST_TIME 76 8890
TEST_TIME 77 8848
TEST_TIME 78 8813
TEST_TIME 79 8961
TEST_TIME 80 8936
TEST_TIME 81 8923
TEST_TIME 82 8978
TEST_TIME 83 8890
TEST_TIME 84 8825
TEST_TIME 85 8803
TEST_TIME 86 8811
TEST_TIME 87 8772
TEST_TIME 88 8761
TEST_TIME 89 8744
TEST_TIME 90 8730
TEST_TIME 91 8698
TEST_TIME 92 8674
TEST_TIME 93 8647
TEST_TIME 94 8672
TEST_TIME 95 8720
TEST_TIME 96 8694
TEST_TIME 97 8689
TEST_TIME 98 8742
TEST_TIME 99 8775
TEST_TIME 100 8762
TEST_TIME 101 8766
TEST_TIME 102 8778
TEST_TIME 103 8837
TEST_TIME 104 8822
TEST_TIME 105 8844
TEST_TIME 106 8857
TEST_TIME 107 8800
TEST_TIME 108 8791
TEST_TIME 109 8761
TEST_TIME 110 8726
TEST_TIME 111 8675
TEST_TIME 112 8663
TEST_TIME 113 8657
TEST_TIME 114 8645
TEST_TIME 115 8635
TEST_TIME 116 8627
TEST_TIME 117 8653
TEST_TIME 118 8645
TEST_TIME 119 8636
TEST_TIME 120 8571

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-07-2022 04:38:32 to 09-07-2022 04:38:35.

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 1431462 1431462 0 0.00 0 17174 14 0 0
doc_list_biud 1431432 1431432 0 0.00 0 113727 47 0 0
sp_add_invoice_to_stock 4801 4690 111 2.31 65 189045 26539 0 111
sp_cancel_adding_invoice 5088 5088 0 0.00 7 116014 1500 0 0
sp_cancel_client_order 1612 1612 0 0.00 7 9204 476 0 0
sp_cancel_customer_reserve 505860 505860 0 0.00 1 32765 117 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 4 15162 166 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 4 17278 210 0 0
sp_cancel_supplier_invoice 5687 5677 10 0.17 3 113761 1146 0 10
sp_cancel_supplier_order 386 386 0 0.00 5 1547832 195755 0 0
sp_cancel_write_off 8797 8797 0 0.00 2 17179 199 0 0
sp_client_order 19087 19087 0 0.00 5 23962 530 0 0
sp_customer_reserve 441002 441002 0 0.00 0 47817 244 0 0
sp_fill_shopping_cart 29119 29119 0 0.00 1 17610 93 0 0
sp_fill_shopping_cart_clo_res 440970 440970 0 0.00 0 19516 20 0 0
sp_get_clo_for_invoice 4640 4640 0 0.00 0 25305 377 0 0
sp_kill_cost_storno 14944 14944 0 0.00 0 10320 93 0 0
sp_kill_qstorno_ret_qs2qd 1026580 1026580 0 0.00 0 113722 49 0 0
sp_kill_qty_storno 541428 541428 0 0.00 0 113725 115 0 0
sp_lock_selected_doc 19397 19397 0 0.00 0 9774 9 0 0
sp_make_cost_storno 26050 26050 0 0.00 0 14570 89 0 0
sp_multiply_rows_for_pdistr 26044 26044 0 0.00 0 10350 43 0 0
sp_multiply_rows_for_qdistr 19087 19087 0 0.00 0 23722 198 0 0
sp_pay_from_customer 6683 6683 0 0.00 5 15895 202 0 0
sp_pay_to_supplier 5355 5355 0 0.00 6 15970 233 0 0
sp_payment_common 12036 12036 0 0.00 2 12192 57 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 9194 385 0 0
sp_qd_handle_on_invoice_upd_sts 9723 9723 0 0.00 0 54782 639 0 0
sp_qd_handle_on_reserve_upd_sts 9373 9373 0 0.00 0 14390 72 0 0
sp_reserve_write_off 9375 9375 0 0.00 8 16398 327 0 0
sp_supplier_invoice 5050 5050 0 0.00 10 88815 3580 0 0
sp_supplier_order 4986 4986 0 0.00 9 49677 1470 0 0
srv_aggregate_perf_data 100 100 0 0.00 1917 293404 20146 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 737 141285 36866 0 0
srv_find_qd_qs_mism 979906 979906 0 0.00 0 17172 10 0 0
srv_make_invnt_saldo 442 442 0 0.00 39 109329 4622 0 0
srv_make_money_saldo 881 881 0 0.00 1 13771 358 0 0
srv_recalc_idx_stat 4 4 0 0.00 95773 631043 348633 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4690 4690 0 0.00 1 11839 1143 0 0
t$perf-norm:sp_cancel_adding_invoice 346 346 0 0.00 2 7725 704 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 5737 36 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 612 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 1865 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 547 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1342 39 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 114493 8865 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 408 14 0 0
t$perf-norm:sp_client_order 19087 19087 0 0.00 0 8951 42 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 2077 20 0 0
t$perf-norm:sp_pay_from_customer 6683 6683 0 0.00 0 9823 26 0 0
t$perf-norm:sp_pay_to_supplier 5355 5355 0 0.00 0 5406 23 0 0
t$perf-norm:sp_reserve_write_off 9378 9378 0 0.00 0 8506 25 0 0
t$perf-norm:sp_supplier_invoice 5051 5051 0 0.00 0 19961 39 0 0
t$perf-norm:sp_supplier_order 4986 4986 0 0.00 0 3690 35 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 330 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 24 3 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 1640 23 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 665 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 12 202 114 0 0
v_add_invoice_to_stock 9490 9268 222 2.33 0 7014 33 0 222
v_all_customers 35048 35048 0 0.00 0 66 4 0 0
v_all_suppliers 28148 28148 0 0.00 0 144 5 0 0
v_all_wares 38174 38174 0 0.00 0 93 4 0 0
v_cancel_adding_invoice 692 692 0 0.00 0 2413 25 0 0
v_cancel_client_order 3224 3224 0 0.00 0 5199 16 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 7584 25 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 6824 25 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 6900 28 0 0
v_cancel_supplier_invoice 850 830 20 2.35 0 2362 31 0 20
v_cancel_supplier_order 772 772 0 0.00 0 926 14 0 0
v_cancel_write_off 1880 1880 0 0.00 0 4310 29 0 0
v_max_id_clo_ord 4985 4985 0 0.00 0 567 6 0 0
v_max_id_clo_res 933 933 0 0.00 0 1660 15 0 0
v_max_id_ord_sup 5047 5047 0 0.00 0 5869 8 0 0
v_max_non_paid_invoice 1313 1313 0 0.00 0 233 8 0 0
v_max_non_paid_realizn 3482 3482 0 0.00 0 2829 8 0 0
v_min_id_clo_ord 4985 4985 0 0.00 0 1365 8 0 0
v_min_id_clo_res 933 933 0 0.00 0 6397 22 0 0
v_min_id_ord_sup 5047 5047 0 0.00 0 3236 9 0 0
v_min_non_paid_invoice 5355 5355 0 0.00 0 12144 29 0 0
v_min_non_paid_realizn 6681 6681 0 0.00 0 12156 21 0 0
v_our_firm 9524 9524 0 0.00 0 61 5 0 0
v_reserve_write_off 18746 18746 0 0.00 0 8009 16 0 0
x_lock_depdocs_on_canc_invoice 10350 10350 0 0.00 0 28372 141 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 16517 470 0 0
x_make_qstorno_1000_1200 4986 4986 0 0.00 2 48322 1275 0 0
x_make_qstorno_1200_2000 5050 5050 0 0.00 4 88754 3326 0 0
x_make_qstorno_2100_3300 440999 440999 0 0.00 0 47813 210 0 0

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

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
02:07:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 20607 8192000000 9725647488 10023088128 803315056 8.930 100 88 176 0 832649568 7897952 21436560
02:09:50 17470 8192000000 9767331744 10076573696 842927344 9.329 100 92 184 0 876753728 12465600 21360784
02:11:13 15369 8192000000 9798925808 10114002944 862396912 9.524 100 86 170 0 902716224 20929296 19390016
02:12:31 12097 8192000000 9845703712 10179215360 899504416 9.893 100 76 152 0 954864800 38993088 16367296
02:13:45 8868 8192000000 9895011664 10239307776 943723456 10.330 100 79 154 1 1010196128 50096400 16376272
02:15:00 2869 8192000000 9949905968 10300264448 983905744 10.722 100 87 170 0 1063269040 62066512 17296784
02:16:06 1388 8192000000 9986478448 10345132032 1006826736 10.945 100 86 170 0 1105929136 81800000 17302400
02:17:09 2956 8192000000 9992192048 10365341696 1025238704 11.123 100 84 166 0 1110217136 69047200 15931232
02:18:16 971 8192000000 10030094000 10407473152 1048983488 11.351 100 91 174 1 1149573088 82976976 17612624
02:19:19 1138 8192000000 10038026320 10414419968 1062885024 11.484 100 94 182 0 1158858048 77529280 18443744
02:20:24 1117 8192000000 10040943072 10417233920 1071375248 11.565 100 94 171 1 1161109664 72768064 16966352
02:21:27 1411 8192000000 10046783712 10427424768 1075187184 11.602 100 95 180 2 1166676704 72927440 18562080
02:22:29 1871 8192000000 10058281776 10442047488 1079878368 11.646 100 89 168 0 1178506144 82408144 16219632
02:23:32 1040 8192000000 10043684832 10430136320 1087432512 11.718 100 91 169 3 1164547888 60659392 16455984
02:24:34 1976 8192000000 10067632928 10464808960 1093648896 11.777 100 72 143 0 1188034320 80427248 13958176
02:25:41 1058 8192000000 10085888224 10472153088 1095567392 11.796 100 98 182 1 1205732032 90647984 19516656
02:26:43 1258 8192000000 10047958144 10433830912 1093548848 11.776 100 88 161 1 1168604288 57177648 17877792
02:27:45 2891 8192000000 10046618384 10442764288 1096721264 11.807 100 74 141 1 1170685488 60293872 13670352
02:28:49 1218 8192000000 10049902432 10442506240 1094663456 11.787 100 82 154 3 1173402448 62925216 15813776
02:29:51 1089 8192000000 10055959600 10447888384 1091688976 11.759 100 91 176 1 1178870480 68671280 18510224
02:30:53 1205 8192000000 10064610576 10456236032 1091437840 11.756 100 94 177 1 1191309824 82830064 17041920
02:31:55 1106 8192000000 10045777520 10426142720 1068632800 11.539 100 96 183 1 1164943248 78137072 18173376
02:32:57 1219 8192000000 10018546016 10397970432 1046723200 11.329 99 97 186 3 1141356416 76065856 18567360
02:34:00 1283 8192000000 10024952928 10403024896 1060684432 11.463 100 91 178 4 1147354000 67485664 19183904
02:35:02 1034 8192000000 10009255168 10383978496 1054431920 11.403 99 91 178 0 1131604608 59499936 17672752
02:36:05 1983 8192000000 9999005008 10381598720 1043085504 11.294 100 72 131 1 1117530816 62415376 12029936
02:37:08 906 8192000000 9975506368 10351210496 1038322896 11.249 100 95 181 2 1100063136 43201952 18538288
02:38:14 1994 8192000000 10001219856 10377306112 1051840320 11.378 100 73 133 1 1118885456 54624816 12420320
02:39:21 45362 8192000000 10042042032 10448474112 1075090176 11.601 100 61 121 0 1176302288 88180848 13031264
02:41:16 13642 8192000000 10077455552 10465669120 1085671920 11.701 100 66 127 2 1201870352 103150048 13048384
02:42:36 1402 8192000000 10104465840 10486575104 1092045888 11.762 100 85 160 1 1217652736 108157440 17449408
02:43:39 2669 8192000000 10132751200 10523320320 1107441824 11.908 100 83 161 1 1252838704 128439232 16957648
02:44:47 1536 8192000000 10159854544 10563276800 1118702960 12.015 100 83 157 1 1275408320 140528768 16176592
02:45:59 5104 8192000000 10175061776 10573615104 1132238000 12.142 100 68 132 0 1289826928 142800272 14788656
02:47:06 2441 8192000000 10207287568 10601996288 1148377088 12.294 100 84 166 0 1322943072 155674864 18891120
02:48:13 2250 8192000000 10256601296 10649968640 1158209760 12.386 100 87 168 2 1375140240 198600496 18329984
02:49:20 974 8192000000 10285127344 10684702720 1164258128 12.443 100 88 172 4 1399462064 215351696 19852240
02:50:23 1399 8192000000 10310406768 10730045440 1174469232 12.539 100 92 182 3 1424852480 228605520 21777728
02:51:29 1337 8192000000 10358073680 10760921088 1185476176 12.641 100 94 182 1 1467607360 260848096 21283088
02:52:34 1024 8192000000 10398245424 10805317632 1191548608 12.698 100 98 186 3 1508093776 293981264 22563904
02:53:39 2060 8192000000 10412958176 10816512000 1185469088 12.641 100 89 172 1 1519268256 314471056 19328112
02:54:47 1495 8192000000 10465338736 10870042624 1201423776 12.790 100 93 183 0 1566309408 344054704 20830928
02:55:54 866 8192000000 10504776832 10910425088 1213026864 12.897 100 95 185 1 1608883888 374640416 21216608
02:56:59 858 8192000000 10562778432 10971832320 1216526864 12.930 100 97 188 1 1670147600 430270000 23350736
02:58:03 1030 8192000000 10614565696 11034664960 1223729840 12.996 100 89 178 0 1718646960 473256288 21660832
02:59:09 842 8192000000 10627105648 11078656000 1206563984 12.837 100 93 181 2 1728954176 500154800 22235392
03:00:13 1223 8192000000 10645747360 11061243904 1202692608 12.801 100 91 168 1 1748218784 525443648 20082528
03:01:16 881 8192000000 10659975504 11114749952 1203910720 12.813 100 98 189 0 1763100672 535675616 23514336
03:02:22 736 8192000000 10704556352 11121799168 1212071936 12.888 100 98 188 0 1808717696 571937824 24707936
03:03:25 1385 8192000000 10637578608 11168231424 1191812288 12.700 100 95 190 1 1742002224 526602304 23587632
03:04:30 730 8192000000 10658787280 11118395392 1194845232 12.728 100 91 178 0 1765712112 549057120 21809760
03:05:34 23228 8192000000 10611296608 11049836544 1179870016 12.589 100 51 102 1 1733920544 540223952 13826576
03:07:30 17732 8192000000 10451041328 10957463552 1178896304 12.580 100 33 64 0 1586885984 399424560 8565120
03:08:59 37953 8192000000 10438152432 10892808192 1172080720 12.516 100 26 52 0 1541442096 362801680 6559696
03:10:59 18778 8192000000 10349128032 10844155904 1150059200 12.310 100 21 42 0 1482054320 326612192 5382928
03:12:21 3931 8192000000 10229851744 10773487616 1134885904 12.167 99 67 123 2 1321182320 172517072 13779344
03:13:28 904 8192000000 10266933088 10782531584 1147789472 12.289 100 97 195 0 1370173040 200660816 21722752
03:14:30 4194 8192000000 10186138688 10644545536 1134863968 12.167 99 51 92 3 1279387072 134068160 10454944
03:15:43 5644 8192000000 10178372480 10596376576 1149253632 12.302 99 67 135 0 1275155040 111071776 14829632
03:16:51 122164 8192000000 10225057168 10698362880 1140627568 12.221 99 43 85 0 1307237472 156150368 10459536
03:20:18 80334 8192000000 10061002864 10538749952 1106760288 11.902 98 9 18 0 1234127424 125256192 2110944
03:22:57 73649 8192000000 10058561312 10484379648 1054882496 11.407 94 34 62 1 1105851936 44382704 6586736
03:25:17 37383 8192000000 10082907552 10525224960 1103670976 11.872 99 27 54 0 1164136944 54927744 5538224
03:26:57 79741 8192000000 10077456992 10555092992 1112566800 11.957 99 15 27 0 1163221024 47717616 2936608
03:29:26 63910 8192000000 10157483632 10607763456 1131966192 12.140 99 22 42 0 1217671936 81155184 4550560
03:31:31 141285 8192000000 10096179312 10675134464 1098021520 11.819 97 17 34 1 1226648480 124543040 4083920
03:34:58 65921 8192000000 10075887280 10528575488 1130027696 12.122 100 34 68 0 1170596432 33401808 7166928
03:37:06 26329 8192000000 10085080960 10524835840 1114236512 11.973 98 37 71 0 1166200896 45362608 6601776
03:38:36 66882 8192000000 10110309824 10584068096 1121951760 12.045 99 29 58 0 1205538448 77563728 6022960
03:41:39 58986 8192000000 10051308352 10546679808 1120958192 12.036 99 9 18 0 1168457760 45981216 1518352
03:43:40 57783 8192000000 10055469776 10523852800 1136487920 12.182 100 7 14 0 1169199920 31718272 993728
03:45:54 66419 8192000000 10051739024 10524655616 1109233216 11.925 97 21 40 1 1146575584 33277728 4064640
03:48:01 34464 8192000000 10104993168 10547929088 1117591648 12.004 98 31 62 1 1148153552 24263424 6298480
03:49:40 61138 8192000000 10078280064 10561110016 1091096576 11.753 96 17 35 0 1148978704 54384112 3498016
03:51:47 97648 8192000000 10081910944 10548879360 1112327248 11.954 99 15 30 0 1146528640 31290896 2910496
03:54:26 26583 8192000000 10099468704 10542931968 1112892112 11.960 98 35 68 0 1196547952 76707536 6948304
03:55:53 57527 8192000000 10053435104 10532118528 1098550672 11.824 97 11 22 0 1165595136 64470464 2574000
03:57:55 21405 8192000000 10084713152 10525073408 1123557584 12.061 99 50 100 2 1156431536 22227072 10646880
03:59:21 118628 8192000000 10056709824 10510725120 1119659024 12.024 99 24 48 0 1187807824 62155440 5993360
04:02:30 79753 8192000000 10058941488 10544562176 1101739392 11.854 98 25 50 0 1146358288 39669952 4948944
04:04:59 61467 8192000000 10082895408 10574172160 1129921552 12.121 100 13 26 0 1199491520 66261904 3308064
04:07:01 76378 8192000000 10065528928 10560765952 1130526672 12.126 99 22 44 0 1192623568 57734240 4362656
04:09:43 30135 8192000000 10065061648 10514972672 1102606144 11.862 97 25 45 0 1153190672 46076576 4507952
04:11:14 86800 8192000000 10053566736 10535051264 1100217056 11.840 98 16 32 0 1146576864 43248720 3111088
04:13:42 32136 8192000000 10098629296 10556530688 1106743632 11.902 98 28 52 1 1182457024 70064336 5649056
04:15:16 52841 8192000000 10100347856 10546765824 1113184960 11.963 98 31 62 1 1226360864 106328416 6847488
04:17:10 68327 8192000000 10096624800 10540441600 1110799744 11.940 98 25 50 0 1186033136 69773760 5459632
04:19:20 76339 8192000000 10074801296 10535739392 1115346720 11.983 98 26 51 0 1186163136 65373216 5443200
04:21:39 16349 8192000000 10098041600 10533527552 1127137712 12.094 100 58 114 0 1186158672 46770640 12250320
04:22:57 92082 8192000000 10111644928 10602135552 1115814880 11.987 99 28 54 0 1186199968 65116752 5268336
04:27:36 69093 8192000000 10062907840 10510815232 1097558016 11.814 98 8 16 0 1130303088 31019616 1725456
04:30:46 109420 8192000000 10073854000 10536988672 1108875312 11.922 98 18 34 0 1173533616 60816544 3841760
04:33:53 30153 8192000000 10089489552 10564071424 1131719264 12.138 100 29 59 0 1224523664 85843008 6961392
04:35:33 38620 8192000000 10113859904 10547666944 1108600240 11.919 97 24 46 0 1173262896 59531696 5130960
04:37:17 22915 8192000000 8928055504 9151680512 91231104 1.101 8 5 8 1 116800784 24423344 1146336

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-07-2022 04:38:42 to 09-07-2022 04:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          209578
MON$OLDEST_ACTIVE               209579
MON$OLDEST_SNAPSHOT             209579
MON$NEXT_TRANSACTION            209579
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                       2116160
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             756
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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.07.22 04:39:02. 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-07-09 04:39:04 No severe PSQL-related problems occured
09.07.22 04:39:06. End of report.