Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.05.2022 13:39
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 16355940 kB
MemAvailable 31413596 kB
Buffers 0 kB
Cached 15393756 kB
SwapCached 2548 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-05-27 13:37:15
DTS_END                         2022-05-27 13:37:15.7570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42108
TRN_ID                          208266
ATT_ID                          574
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: 8448.04 31658 1013765
customer order: creation 157.53 501 18904
customer order: refuse 13.66 468 1639
order to supplier: creation 42.22 1504 5066
order to supplier: removal 3.13 186927 375
invoice (draft): creation 42.48 4196 5097
invoice (draft): removal 45.93 1109 5511
invoice accept: apply 39.30 26295 4716
invoice accept: cancel 41.52 1311 4982
customer reserve: creation 3684.80 245 442176
customer reserve: removal 4109.13 111 493095
realization accept: apply 77.26 308 9271
realization accept: cancel 70.39 175 8447
payment from customer: creation 54.44 190 6533
payment from customer: removal 5.38 168 646
payment to supplier: creation 46.15 240 5538
payment to supplier: removal 3.27 143 392
service: total inventory turnovers 3.62 4703 434
service: total monetary turnovers 6.90 394 828
service: aggregate perf. data 0.93 15741 111
service: refresh index statistics 0.03 388421 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-05-2022 13:40:14 to 27-05-2022 13:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6230
TEST_TIME 2 5875
TEST_TIME 3 5679
TEST_TIME 4 5395
TEST_TIME 5 5638
TEST_TIME 6 5381
TEST_TIME 7 5372
TEST_TIME 8 5319
TEST_TIME 9 5479
TEST_TIME 10 5674
TEST_TIME 11 5586
TEST_TIME 12 5751
TEST_TIME 13 5888
TEST_TIME 14 5956
TEST_TIME 15 6106
TEST_TIME 16 6240
TEST_TIME 17 6499
TEST_TIME 18 6523
TEST_TIME 19 6539
TEST_TIME 20 6620
TEST_TIME 21 6876
TEST_TIME 22 7360
TEST_TIME 23 7768
TEST_TIME 24 8056
TEST_TIME 25 8185
TEST_TIME 26 8257
TEST_TIME 27 8165
TEST_TIME 28 8566
TEST_TIME 29 8907
TEST_TIME 30 9261
TEST_TIME 31 9699
TEST_TIME 32 9927
TEST_TIME 33 10050
TEST_TIME 34 10126
TEST_TIME 35 10322
TEST_TIME 36 10166
TEST_TIME 37 10083
TEST_TIME 38 9975
TEST_TIME 39 9911
TEST_TIME 40 9873
TEST_TIME 41 9870
TEST_TIME 42 9765
TEST_TIME 43 9710
TEST_TIME 44 9705
TEST_TIME 45 9682
TEST_TIME 46 9531
TEST_TIME 47 9466
TEST_TIME 48 9478
TEST_TIME 49 9359
TEST_TIME 50 9223
TEST_TIME 51 9207
TEST_TIME 52 9119
TEST_TIME 53 9038
TEST_TIME 54 9016
TEST_TIME 55 9034
TEST_TIME 56 8921
TEST_TIME 57 8837
TEST_TIME 58 8738
TEST_TIME 59 8706
TEST_TIME 60 8790
TEST_TIME 61 8722
TEST_TIME 62 8795
TEST_TIME 63 8737
TEST_TIME 64 8715
TEST_TIME 65 8781
TEST_TIME 66 8737
TEST_TIME 67 8675
TEST_TIME 68 8709
TEST_TIME 69 8722
TEST_TIME 70 8775
TEST_TIME 71 8722
TEST_TIME 72 8860
TEST_TIME 73 8909
TEST_TIME 74 8933
TEST_TIME 75 8846
TEST_TIME 76 8744
TEST_TIME 77 8698
TEST_TIME 78 8658
TEST_TIME 79 8614
TEST_TIME 80 8586
TEST_TIME 81 8530
TEST_TIME 82 8460
TEST_TIME 83 8406
TEST_TIME 84 8382
TEST_TIME 85 8387
TEST_TIME 86 8419
TEST_TIME 87 8347
TEST_TIME 88 8340
TEST_TIME 89 8374
TEST_TIME 90 8360
TEST_TIME 91 8378
TEST_TIME 92 8364
TEST_TIME 93 8359
TEST_TIME 94 8405
TEST_TIME 95 8405
TEST_TIME 96 8413
TEST_TIME 97 8454
TEST_TIME 98 8490
TEST_TIME 99 8481
TEST_TIME 100 8471
TEST_TIME 101 8475
TEST_TIME 102 8469
TEST_TIME 103 8419
TEST_TIME 104 8438
TEST_TIME 105 8418
TEST_TIME 106 8371
TEST_TIME 107 8353
TEST_TIME 108 8373
TEST_TIME 109 8372
TEST_TIME 110 8369
TEST_TIME 111 8410
TEST_TIME 112 8434
TEST_TIME 113 8404
TEST_TIME 114 8423
TEST_TIME 115 8427
TEST_TIME 116 8544
TEST_TIME 117 8582
TEST_TIME 118 8576
TEST_TIME 119 8537
TEST_TIME 120 8476

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-05-2022 13:40:18 to 27-05-2022 13:40:21.

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 1417778 1417778 0 0.00 0 22004 14 0 0
doc_list_biud 1417761 1417761 0 0.00 0 35636 45 0 0
sp_add_invoice_to_stock 4828 4716 112 2.31 32 170975 26296 0 112
sp_cancel_adding_invoice 4982 4982 0 0.00 6 69982 1311 0 0
sp_cancel_client_order 1639 1639 0 0.00 6 9556 469 0 0
sp_cancel_customer_reserve 493095 493095 0 0.00 1 35639 111 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 4 10985 169 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 4813 144 0 0
sp_cancel_supplier_invoice 5514 5511 3 0.05 3 22763 1109 0 3
sp_cancel_supplier_order 375 375 0 0.00 15 1605562 186928 0 0
sp_cancel_write_off 8447 8447 0 0.00 2 21510 175 0 0
sp_client_order 18904 18904 0 0.00 6 19770 502 0 0
sp_customer_reserve 442176 442176 0 0.00 1 44628 245 0 0
sp_fill_shopping_cart 29059 29059 0 0.00 1 25303 92 0 0
sp_fill_shopping_cart_clo_res 442139 442139 0 0.00 0 19578 23 0 0
sp_get_clo_for_invoice 4671 4671 0 0.00 0 52164 406 0 0
sp_kill_cost_storno 14467 14467 0 0.00 0 17102 90 0 0
sp_kill_qstorno_ret_qs2qd 1000523 1000523 0 0.00 0 35634 49 0 0
sp_kill_qty_storno 527990 527990 0 0.00 0 35636 112 0 0
sp_lock_selected_doc 19185 19185 0 0.00 0 6231 6 0 0
sp_make_cost_storno 26012 26012 0 0.00 0 18508 98 0 0
sp_multiply_rows_for_pdistr 26011 26011 0 0.00 0 9929 41 0 0
sp_multiply_rows_for_qdistr 18903 18903 0 0.00 0 19587 181 0 0
sp_pay_from_customer 6533 6533 0 0.00 5 17658 191 0 0
sp_pay_to_supplier 5538 5538 0 0.00 6 18524 240 0 0
sp_payment_common 12070 12070 0 0.00 2 10704 53 0 0
sp_qd_handle_on_cancel_clo 1639 1639 0 0.00 0 9530 386 0 0
sp_qd_handle_on_invoice_upd_sts 9652 9652 0 0.00 0 23763 597 0 0
sp_qd_handle_on_reserve_upd_sts 9271 9271 0 0.00 0 16849 70 0 0
sp_reserve_write_off 9271 9271 0 0.00 8 17768 309 0 0
sp_supplier_invoice 5097 5097 0 0.00 10 89890 4197 0 0
sp_supplier_order 5066 5066 0 0.00 7 26866 1504 0 0
srv_aggregate_perf_data 111 111 0 0.00 2263 121869 15742 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 771 135069 35061 0 0
srv_find_qd_qs_mism 966337 966337 0 0.00 0 22003 10 0 0
srv_make_invnt_saldo 434 434 0 0.00 81 126603 4704 0 0
srv_make_money_saldo 828 828 0 0.00 1 19385 395 0 0
srv_recalc_idx_stat 4 4 0 0.00 270840 504658 388422 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 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4727 4727 0 0.00 1 12672 974 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 2 9606 433 0 0
t$perf-norm:sp_cancel_client_order 1639 1639 0 0.00 0 1686 28 0 0
t$perf-norm:sp_cancel_customer_reserve 606 606 0 0.00 0 1440 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 2227 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 3759 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 2354 42 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 1 113172 7759 0 0
t$perf-norm:sp_cancel_write_off 860 860 0 0.00 0 5205 31 0 0
t$perf-norm:sp_client_order 18904 18904 0 0.00 0 6880 37 0 0
t$perf-norm:sp_customer_reserve 908 908 0 0.00 0 840 14 0 0
t$perf-norm:sp_pay_from_customer 6533 6533 0 0.00 0 14945 22 0 0
t$perf-norm:sp_pay_to_supplier 5538 5538 0 0.00 0 5200 21 0 0
t$perf-norm:sp_reserve_write_off 9271 9271 0 0.00 0 6674 24 0 0
t$perf-norm:sp_supplier_invoice 5097 5097 0 0.00 0 4455 32 0 0
t$perf-norm:sp_supplier_order 5066 5066 0 0.00 0 5601 34 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 982 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 218 9 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 525 17 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 1302 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 289 78 0 0
v_add_invoice_to_stock 9556 9332 224 2.34 0 8203 30 0 224
v_all_customers 34436 34436 0 0.00 0 70 3 0 0
v_all_suppliers 28482 28482 0 0.00 0 149 4 0 0
v_all_wares 37798 37798 0 0.00 0 142 3 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 3138 19 0 0
v_cancel_client_order 3278 3278 0 0.00 0 7481 16 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 630 12 0 0
v_cancel_customer_reserve 1212 1212 0 0.00 1 11366 35 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1877 16 0 0
v_cancel_supplier_invoice 706 700 6 0.84 0 1316 26 0 6
v_cancel_supplier_order 750 750 0 0.00 0 1606 15 0 0
v_cancel_write_off 1720 1720 0 0.00 0 8170 32 0 0
v_max_id_clo_ord 5064 5064 0 0.00 0 1038 5 0 0
v_max_id_clo_res 908 908 0 0.00 0 2163 10 0 0
v_max_id_ord_sup 5095 5095 0 0.00 0 6003 8 0 0
v_max_non_paid_invoice 1455 1455 0 0.00 0 5087 10 0 0
v_max_non_paid_realizn 3463 3463 0 0.00 0 3055 8 0 0
v_min_id_clo_ord 5064 5064 0 0.00 0 2993 9 0 0
v_min_id_clo_res 908 908 0 0.00 0 2682 13 0 0
v_min_id_ord_sup 5095 5095 0 0.00 0 9137 19 0 0
v_min_non_paid_invoice 5537 5537 0 0.00 0 8736 25 0 0
v_min_non_paid_realizn 6533 6533 0 0.00 0 10699 24 0 0
v_our_firm 9502 9502 0 0.00 0 52 4 0 0
v_reserve_write_off 18542 18542 0 0.00 0 12800 16 0 0
x_lock_depdocs_on_canc_invoice 10143 10143 0 0.00 0 18938 145 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 3759 434 0 0
x_make_qstorno_1000_1200 5066 5066 0 0.00 3 26842 1328 0 0
x_make_qstorno_1200_2000 5097 5097 0 0.00 4 87457 3913 0 0
x_make_qstorno_2100_3300 442172 442172 0 0.00 0 36881 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-05-2022 13:40:22 to 27-05-2022 13:40:26.

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:19 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:20 30130 8192000000 9719784208 10018623488 797299296 8.869 100 86 172 0 826291856 7629776 21362784
11:09:54 23950 8192000000 9762957728 10070540288 837011488 9.270 100 91 182 0 871542880 12749616 21781776
11:11:23 20059 8192000000 9804415232 10124660736 868880448 9.589 100 82 164 1 910988304 23174448 18933408
11:13:20 2876 8192000000 9901528336 10240360448 950012592 10.391 100 84 166 2 1011812800 44124416 17675792
11:14:26 4077 8192000000 9942321872 10293211136 978654992 10.671 100 89 171 0 1059573488 62924976 17993520
11:15:32 2094 8192000000 9987074176 10347724800 1002109056 10.899 100 95 185 0 1103729200 82802832 18817312
11:16:36 1698 8192000000 10004102736 10365341696 1021563344 11.087 100 91 178 1 1120214336 80695808 17955184
11:17:40 1357 8192000000 10005295280 10372542464 1041713424 11.281 100 91 170 1 1119462448 59818832 17930192
11:18:43 1421 8192000000 10029759360 10404433920 1049825840 11.359 100 91 180 0 1157177344 89620976 17730528
11:19:46 1055 8192000000 10042266416 10418958336 1060521312 11.461 100 97 185 0 1160935056 81650528 18763216
11:20:49 993 8192000000 10042239168 10421125120 1067863280 11.532 100 98 186 0 1166051712 79057136 19131296
11:21:51 1202 8192000000 10051416544 10435366912 1074759952 11.598 100 96 183 0 1173644752 79857552 19027248
11:22:55 2575 8192000000 10039050080 10435211264 1076721536 11.616 100 76 150 1 1163909728 72462784 14725408
11:23:59 763 8192000000 10040745232 10424434688 1088964512 11.733 100 98 183 1 1167963328 61529392 17469424
11:25:02 1299 8192000000 10075168096 10461286400 1087179968 11.716 100 99 197 0 1196974032 91162336 18631728
11:26:05 1265 8192000000 10059969104 10449039360 1091027680 11.752 100 95 175 1 1186098288 76537008 18533600
11:27:07 1477 8192000000 10074268576 10462851072 1094975392 11.790 100 91 167 1 1198523104 86509632 17038080
11:28:11 1665 8192000000 10065940448 10453614592 1093529424 11.776 100 90 162 2 1186147520 77815360 14802736
11:29:15 1541 8192000000 10067768912 10459959296 1101026832 11.847 100 81 156 3 1184181184 67517408 15636944
11:30:17 937 8192000000 10067638864 10457817088 1096686640 11.806 100 89 169 1 1188676368 74775920 17213808
11:31:19 1047 8192000000 10036613584 10420121600 1081421616 11.661 100 95 185 3 1159035136 58319488 19294032
11:32:21 3002 8192000000 10034249648 10411212800 1070429008 11.556 100 89 169 0 1151312304 64452768 16430528
11:33:26 1169 8192000000 10019690416 10397700096 1045500976 11.318 100 96 185 0 1140976880 76213904 19262000
11:34:28 1098 8192000000 10033144080 10417184768 1058446944 11.442 100 97 177 1 1158100016 80714752 18938320
11:35:33 1063 8192000000 9990923904 10366705664 1043540320 11.299 100 95 171 2 1112846464 51370672 17935472
11:36:36 986 8192000000 10022480560 10401972224 1060160928 11.458 100 96 186 2 1143901104 62966224 20773952
11:37:43 7607 8192000000 9997375952 10382024704 1050616560 11.367 100 88 171 3 1114772320 46816928 17338832
11:38:59 4973 8192000000 10034628240 10424582144 1059368656 11.450 100 77 151 0 1154060288 78997600 15694032
11:40:12 5949 8192000000 10059540960 10447142912 1076233424 11.612 100 74 142 1 1184929232 94506448 14189360
11:41:22 14539 8192000000 10091738384 10475921408 1082848240 11.675 99 79 148 2 1208149056 109421024 15879792
11:42:47 16297 8192000000 10104356848 10494099456 1100131984 11.839 100 72 140 0 1214822528 100131376 14559168
11:44:06 2846 8192000000 10175303296 10563014656 1123459904 12.060 100 76 145 0 1298033744 158743648 15830192
11:45:16 2003 8192000000 10216831536 10605547520 1128011632 12.103 100 92 177 1 1335923360 187438192 20473536
11:46:23 1022 8192000000 10256842336 10646306816 1134856224 12.167 100 95 179 1 1366800288 210915424 21028640
11:47:28 1409 8192000000 10292159840 10683555840 1153436176 12.342 100 86 162 0 1408015440 236727712 17851552
11:48:34 1337 8192000000 10329955664 10728542208 1174461408 12.539 100 91 176 0 1443588208 249933872 19192928
11:49:42 2037 8192000000 10366012992 10775887872 1185083072 12.638 100 93 181 0 1480663968 274417024 21163872
11:50:49 1171 8192000000 10388689872 10784149504 1181974976 12.609 100 93 185 1 1497206256 293816176 21415104
11:51:56 941 8192000000 10413405744 10827169792 1189429920 12.678 100 96 185 0 1525042912 314115920 21497072
11:53:00 1872 8192000000 10438871056 10848079872 1187952064 12.664 99 89 176 1 1550785584 342695840 20137680
11:54:05 2752 8192000000 10495862080 10915487744 1201989504 12.795 100 97 190 0 1600088896 377327008 20772384
11:55:11 799 8192000000 10527264816 10933694464 1205170368 12.824 100 96 192 2 1633728432 406823824 21734240
11:56:15 811 8192000000 10574705392 10986291200 1210904480 12.877 100 95 185 1 1685371808 452127024 22340304
11:57:19 1102 8192000000 10573186704 11023089664 1204168032 12.815 100 97 191 0 1679482032 452373264 22940736
11:58:26 908 8192000000 10576859696 11031748608 1203085280 12.805 100 92 180 0 1681861216 456228144 22547792
11:59:30 760 8192000000 10538634624 11027472384 1199400016 12.771 100 97 185 1 1641071472 420392368 21279088
12:00:35 3180 8192000000 10488348512 10951077888 1179680256 12.587 99 66 129 0 1601574768 405903664 15990848
12:01:44 2970 8192000000 10532614928 10961637376 1194805664 12.728 100 77 153 0 1640842640 427993248 18043728
12:02:53 5003 8192000000 10524857744 10959884288 1200091776 12.777 100 67 129 1 1627681840 411780384 15809680
12:04:12 3220 8192000000 10495124976 10953768960 1196000976 12.739 100 74 139 0 1597447104 384431472 17014656
12:05:28 8281 8192000000 10480469568 10989453312 1199474832 12.771 100 55 109 1 1600803952 388555696 12773424
12:06:38 9166 8192000000 10433897840 10910474240 1184408288 12.631 100 41 79 0 1537759552 343360048 9991216
12:08:22 39664 8192000000 10243844400 10766766080 1126590976 12.089 97 19 38 0 1351506672 219844608 5071088
12:10:03 23669 8192000000 10192465824 10634977280 1139899904 12.215 99 19 37 0 1306196448 162175056 4121488
12:11:30 26102 8192000000 10186725040 10626519040 1122199936 12.048 97 30 60 1 1275285872 145904688 7181248
12:12:58 20853 8192000000 10186452272 10610352128 1147639568 12.287 99 60 117 0 1270066592 109292288 13134736
12:14:21 24542 8192000000 10227853136 10671013888 1159898432 12.402 100 59 109 1 1321616272 149420688 12297152
12:16:21 61455 8192000000 10172561456 10632810496 1124261792 12.067 98 18 37 0 1277894816 149488656 4144368
12:18:23 98135 8192000000 10133075024 10625372160 1113599664 11.966 98 17 34 0 1244789344 126951552 4238128
12:22:04 115425 8192000000 10110607024 10569547776 1127971008 12.102 98 22 45 1 1197181136 64825040 4385088
12:25:02 82968 8192000000 10059254848 10512539648 1099521680 11.833 96 31 59 1 1135728848 29420752 6786416
12:27:37 19035 8192000000 10063760352 10490503168 1089637824 11.739 97 55 96 5 1147266704 46910656 10718224
12:28:58 29243 8192000000 10076956272 10517172224 1109190528 11.925 99 21 41 0 1174351392 60508304 4652560
12:30:33 26218 8192000000 10116701120 10564186112 1120503984 12.032 99 36 68 2 1211976112 83927024 7545104
12:32:16 84679 8192000000 10105844976 10575519744 1118711424 12.015 99 14 28 0 1205957312 84362640 2883248
12:35:16 110781 8192000000 10109000032 10571288576 1125455456 12.079 99 28 54 0 1237183504 105632304 6095744
12:38:11 75999 8192000000 10060837840 10582962176 1121999776 12.046 99 22 44 1 1197939312 71419872 4519664
12:40:32 8053 8192000000 10062243920 10493964288 1126482128 12.088 100 55 102 2 1155406224 17967648 10956448
12:41:52 70410 8192000000 10042479488 10529644544 1110607376 11.938 99 25 50 1 1155993600 39872800 5513424
12:44:03 48109 8192000000 10093530112 10528190464 1112604544 11.957 98 28 54 1 1169409184 51077424 5727216
12:45:54 45535 8192000000 10089848816 10553425920 1114460912 11.975 99 17 34 0 1180011456 61795184 3755360
12:47:44 58268 8192000000 10111536464 10543706112 1076490784 11.614 95 20 40 1 1140620368 59760576 4369008
12:49:43 59654 8192000000 10073891072 10556526592 1103082832 11.867 98 9 18 0 1166893664 62189888 1620944
12:51:48 84814 8192000000 10042889392 10479792128 1104742048 11.883 97 18 36 3 1130691840 21537120 4412672
12:54:17 44734 8192000000 10051009744 10489393152 1123973808 12.065 99 30 55 0 1155215872 26015920 5226144
12:56:07 61159 8192000000 10067483984 10535276544 1139271984 12.209 100 13 26 0 1170328720 28446880 2609856
12:58:09 54653 8192000000 10040560112 10484432896 1105323568 11.888 97 9 18 1 1141866976 34614928 1928480
13:00:20 79791 8192000000 10098467872 10567204864 1131633840 12.137 100 22 44 0 1212956592 76659536 4663216
13:02:41 20013 8192000000 10085785552 10544136192 1138724464 12.204 100 32 62 0 1200824112 54935616 7164032
13:04:01 47363 8192000000 10086393216 10533994496 1140946496 12.224 99 49 96 0 1187578064 35409296 11222272
13:05:51 63537 8192000000 10064433232 10541801472 1111233552 11.944 97 24 45 1 1179499728 63207456 5058720
13:07:55 17818 8192000000 10101821488 10544443392 1142939568 12.243 100 51 102 1 1192897616 39381536 10576512
13:09:16 10457 8192000000 10066013968 10487881728 1130030112 12.122 99 71 124 4 1167026432 24444928 12551392
13:10:28 40771 8192000000 10109470960 10561859584 1081930848 11.666 95 36 72 2 1165605552 76141344 7533360
13:12:13 28794 8192000000 10087349504 10519674880 1131905072 12.139 100 35 68 1 1182154624 43214672 7034880
13:13:49 19491 8192000000 10094228800 10539220992 1127303072 12.096 99 50 99 0 1206315440 68824704 10187664
13:15:12 79563 8192000000 10096379648 10543362048 1118017520 12.008 99 32 62 1 1179519152 55408656 6092976
13:17:36 14729 8192000000 10092885920 10515509248 1124923920 12.073 100 36 70 0 1182055552 49454240 7677392
13:18:53 135069 8192000000 10075055920 10512506880 1108881632 11.922 98 19 38 0 1178836432 66074848 3879952
13:22:10 67435 8192000000 10103595168 10578006016 1124398944 12.069 99 10 20 1 1194866544 68345952 2121648
13:24:22 80606 8192000000 10093553424 10568163328 1103446912 11.870 97 15 30 3 1194935456 88134528 3354016
13:26:43 39767 8192000000 10134262720 10584961024 1136047888 12.178 99 34 70 1 1227658720 83411360 8199472
13:28:23 51462 8192000000 10131568000 10621505536 1133106576 12.151 99 29 58 0 1238324016 98789408 6428032
13:30:46 31480 8192000000 10179916432 10622767104 1138527120 12.202 100 29 58 3 1257550160 111901312 7121728
13:32:35 78487 8192000000 10108945024 10605391872 1125798928 12.082 99 22 44 1 1236636928 106077280 4760720
13:34:56 44698 8192000000 10073977216 10529579008 1123329168 12.058 99 22 44 1 1172611056 44635600 4646288
13:36:49 60608 8192000000 9739586992 10149076992 827975776 9.179 73 35 67 1 886535200 51756752 6802672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-05-2022 13:40:28 to 27-05-2022 13:40:44.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-05-2022 13:40:45 to 27-05-2022 13:40:47.

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          208265
MON$OLDEST_ACTIVE               209033
MON$OLDEST_SNAPSHOT             209033
MON$NEXT_TRANSACTION            209033
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                       2049184
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.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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'

27.05.22 13:40:48. 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-05-27 13:40:51 No severe PSQL-related problems occured
27.05.22 13:40:52. End of report.