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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-01 04:37:08
DTS_END                         2021-10-01 04:37:08.8970
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/33474
TRN_ID                          228674
ATT_ID                          611
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: 8940.91 19025 1072909
customer order: creation 168.74 389 20249
customer order: refuse 14.68 389 1761
order to supplier: creation 44.93 1429 5392
order to supplier: removal 3.39 123911 407
invoice (draft): creation 44.12 3528 5294
invoice (draft): removal 51.63 1053 6196
invoice accept: apply 40.00 17992 4800
invoice accept: cancel 46.47 854 5576
customer reserve: creation 3694.58 176 443350
customer reserve: removal 4545.21 71 545425
realization accept: apply 81.13 208 9735
realization accept: cancel 82.83 136 9939
payment from customer: creation 56.78 107 6813
payment from customer: removal 5.59 91 671
payment to supplier: creation 46.74 127 5609
payment to supplier: removal 3.58 75 430
service: total inventory turnovers 3.61 3924 433
service: total monetary turnovers 6.03 483 724
service: aggregate perf. data 0.84 19575 101
service: refresh index statistics 0.03 205974 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-10-2021 04:39:04 to 01-10-2021 04:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3711
TEST_TIME 2 3662
TEST_TIME 3 4313
TEST_TIME 4 5024
TEST_TIME 5 4697
TEST_TIME 6 4370
TEST_TIME 7 4140
TEST_TIME 8 4482
TEST_TIME 9 4653
TEST_TIME 10 4613
TEST_TIME 11 4844
TEST_TIME 12 5145
TEST_TIME 13 5642
TEST_TIME 14 5850
TEST_TIME 15 6026
TEST_TIME 16 5988
TEST_TIME 17 6089
TEST_TIME 18 7267
TEST_TIME 19 7575
TEST_TIME 20 7636
TEST_TIME 21 7738
TEST_TIME 22 7856
TEST_TIME 23 8447
TEST_TIME 24 8456
TEST_TIME 25 8678
TEST_TIME 26 9642
TEST_TIME 27 10426
TEST_TIME 28 10356
TEST_TIME 29 10616
TEST_TIME 30 10455
TEST_TIME 31 10369
TEST_TIME 32 10173
TEST_TIME 33 10212
TEST_TIME 34 10142
TEST_TIME 35 10082
TEST_TIME 36 9934
TEST_TIME 37 9872
TEST_TIME 38 9688
TEST_TIME 39 9598
TEST_TIME 40 9638
TEST_TIME 41 9903
TEST_TIME 42 9904
TEST_TIME 43 9951
TEST_TIME 44 9828
TEST_TIME 45 9660
TEST_TIME 46 9513
TEST_TIME 47 9448
TEST_TIME 48 9370
TEST_TIME 49 9385
TEST_TIME 50 9396
TEST_TIME 51 9453
TEST_TIME 52 9407
TEST_TIME 53 9381
TEST_TIME 54 9282
TEST_TIME 55 9185
TEST_TIME 56 9197
TEST_TIME 57 9164
TEST_TIME 58 9338
TEST_TIME 59 9356
TEST_TIME 60 9306
TEST_TIME 61 9328
TEST_TIME 62 9417
TEST_TIME 63 9400
TEST_TIME 64 9377
TEST_TIME 65 9391
TEST_TIME 66 9454
TEST_TIME 67 9501
TEST_TIME 68 9548
TEST_TIME 69 9597
TEST_TIME 70 9516
TEST_TIME 71 9434
TEST_TIME 72 9384
TEST_TIME 73 9328
TEST_TIME 74 9297
TEST_TIME 75 9305
TEST_TIME 76 9296
TEST_TIME 77 9263
TEST_TIME 78 9206
TEST_TIME 79 9196
TEST_TIME 80 9202
TEST_TIME 81 9132
TEST_TIME 82 9135
TEST_TIME 83 9115
TEST_TIME 84 9126
TEST_TIME 85 9118
TEST_TIME 86 9167
TEST_TIME 87 9195
TEST_TIME 88 9190
TEST_TIME 89 9157
TEST_TIME 90 9193
TEST_TIME 91 9226
TEST_TIME 92 9296
TEST_TIME 93 9319
TEST_TIME 94 9266
TEST_TIME 95 9184
TEST_TIME 96 9137
TEST_TIME 97 9128
TEST_TIME 98 9096
TEST_TIME 99 9044
TEST_TIME 100 9039
TEST_TIME 101 9021
TEST_TIME 102 9068
TEST_TIME 103 9024
TEST_TIME 104 8996
TEST_TIME 105 9026
TEST_TIME 106 9048
TEST_TIME 107 9093
TEST_TIME 108 9163
TEST_TIME 109 9148
TEST_TIME 110 9132
TEST_TIME 111 9120
TEST_TIME 112 9198
TEST_TIME 113 9187
TEST_TIME 114 9232
TEST_TIME 115 9154
TEST_TIME 116 9127
TEST_TIME 117 9094
TEST_TIME 118 9047
TEST_TIME 119 9012
TEST_TIME 120 8944

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2021 04:39:08 to 01-10-2021 04:39:11.

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 1484492 1484492 0 0.00 0 18641 7 0 0
doc_list_biud 1484489 1484489 0 0.00 0 31816 33 0 0
sp_add_invoice_to_stock 4932 4800 132 2.67 34 226330 17993 0 132
sp_cancel_adding_invoice 5576 5576 0 0.00 4 82011 854 0 0
sp_cancel_client_order 1761 1761 0 0.00 4 12214 389 0 0
sp_cancel_customer_reserve 545425 545425 0 0.00 1 31818 71 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 8614 91 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 2933 75 0 0
sp_cancel_supplier_invoice 6208 6196 12 0.19 1 26927 1054 0 12
sp_cancel_supplier_order 407 407 0 0.00 8 1274590 123912 0 0
sp_cancel_write_off 9939 9939 0 0.00 1 17076 136 0 0
sp_client_order 20249 20249 0 0.00 4 29064 389 0 0
sp_customer_reserve 443350 443350 0 0.00 0 110452 177 0 0
sp_fill_shopping_cart 30920 30920 0 0.00 1 12816 46 0 0
sp_fill_shopping_cart_clo_res 443348 443348 0 0.00 0 18256 17 0 0
sp_get_clo_for_invoice 4796 4796 0 0.00 0 26308 408 0 0
sp_kill_cost_storno 16616 16616 0 0.00 0 7737 66 0 0
sp_kill_qstorno_ret_qs2qd 1107392 1107392 0 0.00 0 26906 39 0 0
sp_kill_qty_storno 583835 583835 0 0.00 0 31816 84 0 0
sp_lock_selected_doc 20138 20138 0 0.00 0 7856 6 0 0
sp_make_cost_storno 26953 26953 0 0.00 0 16768 61 0 0
sp_multiply_rows_for_pdistr 26952 26952 0 0.00 0 10074 35 0 0
sp_multiply_rows_for_qdistr 20249 20249 0 0.00 0 24130 202 0 0
sp_pay_from_customer 6813 6813 0 0.00 4 17572 107 0 0
sp_pay_to_supplier 5609 5609 0 0.00 5 17535 127 0 0
sp_payment_common 12421 12421 0 0.00 1 8958 25 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 0 12209 357 0 0
sp_qd_handle_on_invoice_upd_sts 10372 10372 0 0.00 0 26785 389 0 0
sp_qd_handle_on_reserve_upd_sts 9735 9735 0 0.00 0 15780 58 0 0
sp_reserve_write_off 9735 9735 0 0.00 7 20057 209 0 0
sp_supplier_invoice 5294 5294 0 0.00 8 67015 3528 0 0
sp_supplier_order 5392 5392 0 0.00 5 40055 1429 0 0
srv_aggregate_perf_data 101 101 0 0.00 3360 312327 19576 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1491 228288 51210 0 0
srv_find_qd_qs_mism 1027410 1027410 0 0.00 0 9659 5 0 0
srv_make_invnt_saldo 433 433 0 0.00 46 130720 3924 0 0
srv_make_money_saldo 724 724 0 0.00 1 17923 483 0 0
srv_recalc_idx_stat 4 4 0 0.00 139962 267351 205974 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4800 4800 0 0.00 0 28244 204 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 5826 104 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 4998 14 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 926 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 10462 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 3531 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 352 352 0 0.00 0 5051 36 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 30108 1626 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 4767 11 0 0
t$perf-norm:sp_client_order 20249 20249 0 0.00 0 12345 24 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 4186 12 0 0
t$perf-norm:sp_pay_from_customer 6813 6813 0 0.00 0 12327 13 0 0
t$perf-norm:sp_pay_to_supplier 5609 5609 0 0.00 0 7214 12 0 0
t$perf-norm:sp_reserve_write_off 9735 9735 0 0.00 0 25446 16 0 0
t$perf-norm:sp_supplier_invoice 5294 5294 0 0.00 0 7638 22 0 0
t$perf-norm:sp_supplier_order 5392 5392 0 0.00 0 8073 20 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 252 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 863 25 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 1105 12 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 4498 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9856 9592 264 2.67 0 6946 20 0 264
v_all_customers 36808 36808 0 0.00 0 20 1 0 0
v_all_suppliers 29158 29158 0 0.00 0 18 1 0 0
v_all_wares 40488 40488 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 1767 9 0 0
v_cancel_client_order 3522 3522 0 0.00 0 1545 5 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 3884 7 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 2619 10 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 2898 10 0 0
v_cancel_supplier_invoice 728 704 24 3.29 0 2527 19 0 24
v_cancel_supplier_order 814 814 0 0.00 0 1189 8 0 0
v_cancel_write_off 1926 1926 0 0.00 0 2528 16 0 0
v_max_id_clo_ord 5391 5391 0 0.00 0 154 1 0 0
v_max_id_clo_res 980 980 0 0.00 0 2046 12 0 0
v_max_id_ord_sup 5284 5284 0 0.00 0 3683 6 0 0
v_max_non_paid_invoice 1705 1705 0 0.00 0 362 2 0 0
v_max_non_paid_realizn 3621 3621 0 0.00 0 940 3 0 0
v_min_id_clo_ord 5391 5391 0 0.00 0 6313 9 0 0
v_min_id_clo_res 980 980 0 0.00 0 4902 14 0 0
v_min_id_ord_sup 5284 5284 0 0.00 0 4030 7 0 0
v_min_non_paid_invoice 5609 5609 0 0.00 0 8263 17 0 0
v_min_non_paid_realizn 6811 6811 0 0.00 0 8953 20 0 0
v_our_firm 10062 10062 0 0.00 0 13 1 0 0
v_reserve_write_off 19468 19468 0 0.00 0 4131 6 0 0
x_lock_depdocs_on_canc_invoice 11420 11420 0 0.00 0 10793 117 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 3264 323 0 0
x_make_qstorno_1000_1200 5392 5392 0 0.00 2 39841 1344 0 0
x_make_qstorno_1200_2000 5294 5294 0 0.00 3 66816 3349 0 0
x_make_qstorno_2100_3300 443350 443350 0 0.00 0 110450 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2021 04:39:12 to 01-10-2021 04:39:16.

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:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 15639 8192000000 9691396512 9985261568 780953472 8.703 100 83 166 0 808589296 6926224 20709600
02:09:34 21607 8192000000 9752245856 10055831552 828759584 9.187 100 89 178 0 861573504 11648496 21165424
02:11:01 12893 8192000000 9787359424 10102038528 855851904 9.459 100 87 174 0 893794672 17456048 20486720
02:12:20 13270 8192000000 9836423440 10165788672 894358704 9.842 100 68 136 0 950503840 40466016 15679120
02:13:39 8343 8192000000 9870300048 10208641024 933108800 10.225 100 77 154 1 981428816 32322176 15997840
02:14:52 9776 8192000000 9939026816 10286370816 977451456 10.659 100 80 154 1 1043779904 49912544 16415904
02:16:04 5268 8192000000 9977058496 10335633408 1005514192 10.932 100 79 155 2 1091429824 70439808 15475824
02:17:12 2909 8192000000 9998498944 10358304768 1020379312 11.076 100 84 169 1 1115926576 79404960 16142304
02:18:16 2009 8192000000 10023930448 10394238976 1051123808 11.371 100 87 174 1 1141061008 72317728 17619472
02:19:19 29157 8192000000 10003063072 10384588800 1061723776 11.473 100 63 122 2 1122505744 47078208 13703760
02:20:49 4421 8192000000 9997598448 10380546048 1069192128 11.544 100 48 94 2 1109250416 29145952 10912336
02:21:54 8465 8192000000 9992458704 10394157056 1077338224 11.622 100 35 69 0 1104098240 19755824 7004192
02:23:03 5557 8192000000 10003421808 10397564928 1087045952 11.715 100 39 77 2 1121687888 24894064 9747872
02:24:12 76554 8192000000 10004148496 10418642944 1094715008 11.787 100 23 46 0 1115039696 15919456 4405232
02:26:30 7247 8192000000 10023628112 10432548864 1099112976 11.829 100 39 78 1 1138398592 30746320 8539296
02:27:38 4678 8192000000 10012451584 10404466688 1085176432 11.697 99 49 96 1 1120133392 24712240 10244720
02:28:46 4553 8192000000 9997296976 10384125952 1060727904 11.463 99 41 80 1 1101214288 32123888 8362496
02:29:51 29075 8192000000 9956650128 10362310656 988795152 10.770 93 16 32 0 1015658448 23504368 3358928
02:31:21 4609 8192000000 9954268512 10329534464 997570608 10.855 97 50 100 1 1037311216 28501136 11239472
02:32:26 5095 8192000000 9951475808 10329665536 1030318832 11.172 100 40 79 2 1059858192 20680784 8858576
02:33:32 69816 8192000000 9935984880 10329776128 1012201840 10.997 98 31 61 1 1036590832 17885632 6503360
02:35:44 7485 8192000000 9988914080 10391601152 1053747344 11.397 100 37 74 0 1097525184 36552256 7225584
02:36:55 7934 8192000000 9980108544 10374991872 1073662752 11.587 100 31 62 0 1104918784 23702736 7553296
02:38:14 21793 8192000000 10031922336 10433073152 1092338480 11.765 100 40 77 1 1131477104 30339184 8799440
02:39:37 116219 8192000000 10063865808 10513780736 1108642912 11.920 100 31 62 1 1205457392 90201792 6612688
02:42:57 44235 8192000000 10136423600 10553671680 1136948976 12.187 100 39 75 2 1227802048 83052272 7800800
02:45:03 13166 8192000000 10231139776 10647015424 1151779184 12.326 100 61 122 1 1325229728 158733024 14717520
02:46:32 11374 8192000000 10274245936 10684661760 1168396880 12.482 100 62 119 0 1373570608 192175088 12998640
02:47:50 12975 8192000000 10322694880 10742263808 1180460688 12.594 100 55 110 0 1420669456 227820272 12388496
02:49:07 5117 8192000000 10380053680 10795720704 1194411632 12.724 100 62 124 1 1485863504 276939136 14512736
02:50:20 3002 8192000000 10439700640 10850074624 1197923792 12.757 100 89 179 2 1535677680 317595232 20158656
02:51:29 3172 8192000000 10461653936 10884009984 1206942224 12.841 100 80 157 0 1570060848 344397632 18720992
02:52:45 1489 8192000000 10472319552 10884526080 1189106768 12.675 99 89 178 0 1563563776 353220272 21236736
02:53:51 8627 8192000000 10514258928 10936287232 1188870640 12.673 100 70 141 0 1602183280 396070992 17241648
02:55:06 4957 8192000000 10513648048 10977165312 1198715296 12.764 100 88 176 1 1634560192 414880128 20964768
02:56:16 11926 8192000000 10472580752 10901065728 1198716064 12.764 100 56 111 1 1560563040 348712048 13134928
02:57:29 6899 8192000000 10453164688 10876657664 1171796624 12.514 100 55 109 0 1542679312 357644368 13238320
02:58:45 9024 8192000000 10471341664 10907426816 1158225632 12.387 100 50 99 1 1563025120 392507760 12291728
03:00:21 22774 8192000000 10406722672 10877001728 1142771424 12.242 99 24 48 0 1538802544 389287584 6743536
03:01:44 20473 8192000000 10437149504 10887061504 1123429568 12.059 99 24 48 0 1526664736 396292336 6942832
03:03:24 54275 8192000000 10131424848 10669203456 1086776752 11.712 97 26 52 1 1360085280 267013440 6295088
03:05:42 27929 8192000000 10119395808 10560532480 1108320512 11.917 98 21 42 2 1192890448 80006192 4563744
03:07:13 57297 8192000000 10070195696 10508369920 1050713344 11.368 93 17 32 1 1085554912 31720352 3121216
03:09:11 33074 8192000000 10083923680 10512572416 1113539232 11.966 99 33 64 1 1161658048 41595216 6523600
03:10:45 95782 8192000000 10040821648 10500935680 1056455776 11.423 93 9 18 0 1081181248 23335904 1389568
03:13:24 127509 8192000000 10050951552 10491572224 1078636320 11.634 96 15 30 1 1095497360 13721664 3139376
03:16:47 57447 8192000000 10118688752 10592690176 1125994480 12.084 100 7 14 0 1226992784 99288912 1709392
03:18:51 228272 8192000000 10038893008 10471342080 1064224384 11.497 94 8 16 1 1109680480 43583584 1872512
03:24:15 93737 8192000000 10095403088 10521042944 1107180080 11.906 98 31 62 0 1173472464 59616464 6675920
03:26:51 23834 8192000000 10111579104 10539278336 1119837024 12.025 99 38 76 0 1198023792 69701088 8485680
03:28:18 57622 8192000000 10091416864 10547318784 1114672480 11.977 98 26 52 0 1168948256 49148112 5127664
03:30:17 80564 8192000000 10103000064 10550226944 1128907184 12.111 99 17 33 0 1177467760 44450960 4109616
03:33:03 75379 8192000000 10154734800 10601385984 1143150864 12.245 100 25 51 1 1274424480 124932960 6340656
03:35:23 48113 8192000000 10062529072 10522120192 1130536448 12.126 99 25 48 2 1165273296 29311488 5425360
03:37:16 40741 8192000000 10110210752 10545373184 1120310160 12.030 97 35 68 1 1176049920 49055664 6684096
03:39:06 52321 8192000000 10074287360 10526691328 1090048992 11.743 96 16 32 0 1126972256 33838224 3085040
03:41:00 33154 8192000000 10100565440 10556780544 1124179888 12.066 99 23 46 0 1185086688 56304736 4602064
03:42:34 75342 8192000000 10102140512 10571513856 1070911936 11.561 94 10 20 0 1125844864 52824096 2108832
03:44:50 76402 8192000000 10105942304 10581684224 1077438976 11.623 95 14 28 0 1143063824 62260480 3364368
03:47:06 140342 8192000000 10048602384 10500628480 1045894544 11.321 93 23 45 1 1088004112 36705600 5403968
03:50:33 16244 8192000000 10077697584 10519228416 1119832624 12.025 100 22 44 2 1176773152 51695536 5244992
03:51:51 22609 8192000000 10100640752 10544242688 1129207200 12.114 100 47 94 0 1192933616 53772336 9954080
03:53:15 58467 8192000000 10069474912 10504945664 1091927520 11.761 97 28 56 0 1157209136 58922928 6358688
03:55:18 66839 8192000000 10091364816 10540380160 1098308944 11.822 98 22 43 2 1171596704 68865936 4421824
03:57:25 57491 8192000000 10052808480 10499035136 1116693888 11.996 100 11 22 0 1153330800 34511200 2125712
04:00:41 83630 8192000000 10079284416 10544603136 1084418656 11.690 97 13 26 0 1145871472 58695296 2757520
04:03:07 70600 8192000000 10041562656 10530402304 1075738176 11.607 96 23 46 1 1167040144 86427248 4874720
04:05:21 26568 8192000000 10048822224 10490728448 1103192032 11.868 99 29 58 2 1155129632 45779440 6158160
04:06:50 35899 8192000000 10115168128 10572177408 1121245408 12.039 100 47 94 0 1219264080 88976912 9041760
04:08:31 40144 8192000000 10073896736 10535604224 1097977568 11.818 98 39 78 2 1170774032 65138784 7657680
04:10:14 54928 8192000000 10044773792 10513514496 1080350608 11.651 97 7 14 1 1117795376 36172608 1272160
04:12:10 91857 8192000000 10054160720 10510389248 1123190912 12.057 100 24 48 1 1173988800 45290704 5507184
04:14:49 78071 8192000000 10087226656 10569707520 1117808624 12.006 98 13 26 0 1188874224 68131840 2933760
04:17:07 63117 8192000000 10115238480 10564943872 1136427760 12.182 100 15 32 0 1226122032 85495552 4198720
04:19:26 68387 8192000000 10075498160 10541387776 1114858688 11.978 98 9 18 1 1155803792 39284432 1660672
04:21:37 48683 8192000000 10136035648 10619437056 1141677376 12.231 99 31 62 0 1238435232 89341680 7416176
04:23:48 72398 8192000000 10134015040 10605649920 1132532192 12.145 98 22 44 1 1237401920 100016864 4852864
04:26:34 38143 8192000000 10099581408 10540769280 1129379408 12.116 98 37 73 0 1174243120 37214032 7649680
04:28:12 50075 8192000000 10132932368 10587246592 1134923376 12.168 100 22 44 0 1229304288 90091200 4289712
04:30:03 34354 8192000000 10130208240 10601558016 1131650496 12.137 99 41 82 1 1222116224 82453168 8012560
04:31:43 85993 8192000000 10054606576 10500116480 1108737568 11.920 99 13 26 0 1155062832 43620528 2704736
04:34:12 74113 8192000000 10052732480 10514984960 1096661936 11.806 97 16 32 0 1131580720 31713840 3204944
04:36:46 37547 8192000000 9274404080 9611100160 415173312 4.823 37 8 14 1 441118464 24241264 1703888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-10-2021 04:39:18 to 01-10-2021 04:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-10-2021 04:39:34 to 01-10-2021 04:39:36.

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          229098
MON$OLDEST_ACTIVE               229099
MON$OLDEST_SNAPSHOT             229099
MON$NEXT_TRANSACTION            229099
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                       2059104
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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