Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.05.2022 07: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 16463560 kB
MemAvailable 31550100 kB
Buffers 0 kB
Cached 15281252 kB
SwapCached 3276 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-04 07:37:50
DTS_END                         2022-05-04 07:37:14.2900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55176
TRN_ID                          218733
ATT_ID                          259
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: 8739.91 23919 1048789
customer order: creation 167.83 467 20139
customer order: refuse 14.24 367 1709
order to supplier: creation 44.28 1408 5314
order to supplier: removal 3.13 171746 375
invoice (draft): creation 44.48 3349 5338
invoice (draft): removal 46.45 1079 5574
invoice accept: apply 41.54 26623 4985
invoice accept: cancel 42.05 1165 5046
customer reserve: creation 3916.68 252 470001
customer reserve: removal 4137.40 100 496488
realization accept: apply 80.02 274 9602
realization accept: cancel 76.46 170 9175
payment from customer: creation 57.37 183 6884
payment from customer: removal 5.53 129 664
payment to supplier: creation 46.38 215 5565
payment to supplier: removal 3.65 141 438
service: total inventory turnovers 4.10 3622 492
service: total monetary turnovers 7.35 366 882
service: aggregate perf. data 0.95 14039 114
service: refresh index statistics 0.03 252687 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-05-2022 07:39:52 to 04-05-2022 07:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5713
TEST_TIME 2 5431
TEST_TIME 3 5155
TEST_TIME 4 4948
TEST_TIME 5 5587
TEST_TIME 6 5231
TEST_TIME 7 5390
TEST_TIME 8 5344
TEST_TIME 9 5359
TEST_TIME 10 5616
TEST_TIME 11 5875
TEST_TIME 12 5951
TEST_TIME 13 6081
TEST_TIME 14 6199
TEST_TIME 15 6575
TEST_TIME 16 6676
TEST_TIME 17 6975
TEST_TIME 18 7041
TEST_TIME 19 7257
TEST_TIME 20 7498
TEST_TIME 21 7689
TEST_TIME 22 7938
TEST_TIME 23 8106
TEST_TIME 24 8272
TEST_TIME 25 8404
TEST_TIME 26 8551
TEST_TIME 27 8708
TEST_TIME 28 9189
TEST_TIME 29 9465
TEST_TIME 30 9672
TEST_TIME 31 9901
TEST_TIME 32 10157
TEST_TIME 33 11191
TEST_TIME 34 11067
TEST_TIME 35 10816
TEST_TIME 36 10711
TEST_TIME 37 10741
TEST_TIME 38 10569
TEST_TIME 39 10418
TEST_TIME 40 10316
TEST_TIME 41 10144
TEST_TIME 42 10067
TEST_TIME 43 9934
TEST_TIME 44 9867
TEST_TIME 45 9721
TEST_TIME 46 9850
TEST_TIME 47 9802
TEST_TIME 48 9641
TEST_TIME 49 9564
TEST_TIME 50 9635
TEST_TIME 51 9515
TEST_TIME 52 9389
TEST_TIME 53 9383
TEST_TIME 54 9399
TEST_TIME 55 9318
TEST_TIME 56 9239
TEST_TIME 57 9201
TEST_TIME 58 9105
TEST_TIME 59 9015
TEST_TIME 60 8970
TEST_TIME 61 8900
TEST_TIME 62 8906
TEST_TIME 63 8902
TEST_TIME 64 8888
TEST_TIME 65 8883
TEST_TIME 66 8842
TEST_TIME 67 8961
TEST_TIME 68 8991
TEST_TIME 69 9027
TEST_TIME 70 9037
TEST_TIME 71 9005
TEST_TIME 72 9037
TEST_TIME 73 9062
TEST_TIME 74 9053
TEST_TIME 75 9100
TEST_TIME 76 9109
TEST_TIME 77 9230
TEST_TIME 78 9123
TEST_TIME 79 9038
TEST_TIME 80 8983
TEST_TIME 81 8967
TEST_TIME 82 8924
TEST_TIME 83 8894
TEST_TIME 84 8832
TEST_TIME 85 8812
TEST_TIME 86 8828
TEST_TIME 87 8827
TEST_TIME 88 8823
TEST_TIME 89 8829
TEST_TIME 90 8842
TEST_TIME 91 8809
TEST_TIME 92 8840
TEST_TIME 93 8833
TEST_TIME 94 8866
TEST_TIME 95 8852
TEST_TIME 96 8845
TEST_TIME 97 8879
TEST_TIME 98 8903
TEST_TIME 99 8928
TEST_TIME 100 8919
TEST_TIME 101 8862
TEST_TIME 102 8806
TEST_TIME 103 8798
TEST_TIME 104 8770
TEST_TIME 105 8737
TEST_TIME 106 8700
TEST_TIME 107 8721
TEST_TIME 108 8727
TEST_TIME 109 8760
TEST_TIME 110 8729
TEST_TIME 111 8739
TEST_TIME 112 8748
TEST_TIME 113 8794
TEST_TIME 114 8776
TEST_TIME 115 8788
TEST_TIME 116 8757
TEST_TIME 117 8801
TEST_TIME 118 8830
TEST_TIME 119 8826
TEST_TIME 120 8755

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-05-2022 07:39:56 to 04-05-2022 07:39:59.

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 1478711 1478711 0 0.00 0 22724 14 0 0
doc_list_biud 1478700 1478700 0 0.00 0 52178 39 0 0
sp_add_invoice_to_stock 5102 4985 117 2.29 30 197184 26623 0 117
sp_cancel_adding_invoice 5046 5046 0 0.00 6 66110 1166 0 0
sp_cancel_client_order 1709 1709 0 0.00 6 11282 368 0 0
sp_cancel_customer_reserve 496488 496488 0 0.00 1 34465 100 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 4 4793 129 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 4 4818 141 0 0
sp_cancel_supplier_invoice 5583 5574 9 0.16 2 52189 1079 0 9
sp_cancel_supplier_order 375 375 0 0.00 24 1524937 171746 0 0
sp_cancel_write_off 9175 9175 0 0.00 2 8278 171 0 0
sp_client_order 20139 20139 0 0.00 6 29617 468 0 0
sp_customer_reserve 470001 470001 0 0.00 1 55457 252 0 0
sp_fill_shopping_cart 30776 30776 0 0.00 1 16342 80 0 0
sp_fill_shopping_cart_clo_res 469970 469970 0 0.00 0 22438 19 0 0
sp_get_clo_for_invoice 4948 4948 0 0.00 0 40079 341 0 0
sp_kill_cost_storno 15323 15323 0 0.00 0 9537 74 0 0
sp_kill_qstorno_ret_qs2qd 1008100 1008100 0 0.00 0 52177 43 0 0
sp_kill_qty_storno 532912 532912 0 0.00 0 52178 100 0 0
sp_lock_selected_doc 20180 20180 0 0.00 0 8704 8 0 0
sp_make_cost_storno 26998 26998 0 0.00 0 21818 91 0 0
sp_multiply_rows_for_pdistr 26993 26993 0 0.00 0 8875 37 0 0
sp_multiply_rows_for_qdistr 20139 20139 0 0.00 0 14366 174 0 0
sp_pay_from_customer 6884 6884 0 0.00 6 17589 183 0 0
sp_pay_to_supplier 5565 5565 0 0.00 6 22208 215 0 0
sp_payment_common 12447 12447 0 0.00 2 8872 47 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 11265 302 0 0
sp_qd_handle_on_invoice_upd_sts 9989 9989 0 0.00 0 19816 504 0 0
sp_qd_handle_on_reserve_upd_sts 9602 9602 0 0.00 0 12678 67 0 0
sp_reserve_write_off 9602 9602 0 0.00 9 22762 275 0 0
sp_supplier_invoice 5338 5338 0 0.00 9 100549 3350 0 0
sp_supplier_order 5314 5314 0 0.00 7 49815 1408 0 0
srv_aggregate_perf_data 114 114 0 0.00 2611 199442 14040 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 958 142446 36926 0 0
srv_find_qd_qs_mism 999046 999046 0 0.00 0 18575 12 0 0
srv_make_invnt_saldo 492 492 0 0.00 82 67467 3622 0 0
srv_make_money_saldo 882 882 0 0.00 1 12366 366 0 0
srv_recalc_idx_stat 4 4 0 0.00 86569 456143 252687 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 1 26603 825 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 5594 411 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 3324 28 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 799 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 6730 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 312 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1082 35 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 81952 7467 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 3314 20 0 0
t$perf-norm:sp_client_order 20140 20140 0 0.00 0 8440 35 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1746 13 0 0
t$perf-norm:sp_pay_from_customer 6884 6884 0 0.00 0 10377 18 0 0
t$perf-norm:sp_pay_to_supplier 5565 5565 0 0.00 0 5568 21 0 0
t$perf-norm:sp_reserve_write_off 9602 9602 0 0.00 0 8189 21 0 0
t$perf-norm:sp_supplier_invoice 5339 5339 0 0.00 0 6665 33 0 0
t$perf-norm:sp_supplier_order 5314 5314 0 0.00 0 8656 30 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 821 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 215 10 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 3817 26 0 0
t$perf-norm:srv_make_money_saldo 882 882 0 0.00 0 6683 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 193 55 0 0
v_add_invoice_to_stock 10110 9876 234 2.31 0 5957 26 0 234
v_all_customers 36794 36794 0 0.00 0 65 3 0 0
v_all_suppliers 29578 29578 0 0.00 0 121 4 0 0
v_all_wares 40274 40274 0 0.00 0 123 3 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 4803 20 0 0
v_cancel_client_order 3418 3418 0 0.00 0 3198 10 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 4332 13 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 1 7042 16 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 3294 22 0 0
v_cancel_supplier_invoice 834 816 18 2.15 0 4134 26 0 18
v_cancel_supplier_order 750 750 0 0.00 0 2883 19 0 0
v_cancel_write_off 1982 1982 0 0.00 0 7849 30 0 0
v_max_id_clo_ord 5308 5308 0 0.00 0 1379 5 0 0
v_max_id_clo_res 974 974 0 0.00 0 3857 18 0 0
v_max_id_ord_sup 5330 5330 0 0.00 0 5458 7 0 0
v_max_non_paid_invoice 1413 1413 0 0.00 0 477 7 0 0
v_max_non_paid_realizn 3565 3565 0 0.00 0 5774 8 0 0
v_min_id_clo_ord 5308 5308 0 0.00 0 5499 10 0 0
v_min_id_clo_res 974 974 0 0.00 0 1957 9 0 0
v_min_id_ord_sup 5330 5330 0 0.00 0 5451 9 0 0
v_min_non_paid_invoice 5564 5564 0 0.00 0 8866 22 0 0
v_min_non_paid_realizn 6883 6883 0 0.00 0 8403 21 0 0
v_our_firm 10116 10116 0 0.00 0 124 4 0 0
v_reserve_write_off 19204 19204 0 0.00 0 7571 11 0 0
x_lock_depdocs_on_canc_invoice 10212 10212 0 0.00 0 13643 131 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 17864 392 0 0
x_make_qstorno_1000_1200 5314 5314 0 0.00 2 49788 1253 0 0
x_make_qstorno_1200_2000 5338 5338 0 0.00 5 100538 3116 0 0
x_make_qstorno_2100_3300 470000 470000 0 0.00 0 55439 221 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:17 27 8192000000 8804962864 8818712576 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:18 27332 8192000000 9718130160 10014867456 793207456 8.827 100 86 171 0 821454208 7804160 20442592
05:09:52 33065 8192000000 9767163184 10078183424 834886816 9.248 100 85 170 1 867701536 12638736 20175984
05:11:27 16785 8192000000 9812179984 10135511040 880828288 9.708 100 85 169 1 925371920 24908304 19635328
05:12:48 8941 8192000000 9864141328 10199130112 913665280 10.034 100 76 148 0 981600928 51797936 16137712
05:14:01 4491 8192000000 9925850848 10273632256 965452144 10.542 100 91 179 0 1038992528 54714400 18825984
05:15:08 2858 8192000000 9968704912 10328424448 1000774528 10.886 100 86 166 0 1084189728 66287792 17127408
05:16:14 1389 8192000000 10006322368 10375753728 1026899296 11.139 100 93 180 1 1128214512 83282176 18033040
05:17:17 941 8192000000 10019630880 10398171136 1052149824 11.381 100 97 181 1 1147312000 76841264 18320912
05:18:18 1114 8192000000 10032644832 10410151936 1066360784 11.517 100 97 185 1 1156463664 71072704 19030176
05:19:21 1266 8192000000 10036335072 10418470912 1073930688 11.590 100 96 187 2 1159474432 65924480 19619264
05:20:23 1474 8192000000 10064312912 10445344768 1081397168 11.661 100 87 169 1 1177235344 79940416 15897760
05:21:26 1211 8192000000 10045589456 10429427712 1084762896 11.693 100 96 188 0 1164483648 61250128 18470624
05:22:28 1383 8192000000 10043162496 10425696256 1085892288 11.704 100 97 190 0 1162347712 57133216 19322208
05:23:33 1283 8192000000 10064985808 10453094400 1095015744 11.790 100 98 191 0 1185883904 71553776 19314384
05:24:35 958 8192000000 10063350080 10453803008 1097995424 11.819 100 93 172 1 1183382992 67263712 18123856
05:25:36 6944 8192000000 10076996944 10471653376 1099777264 11.836 100 74 144 0 1196386656 81841392 14768000
05:26:44 1669 8192000000 10051401760 10443841536 1105866896 11.893 100 80 150 3 1172954784 51370688 15717200
05:27:46 1901 8192000000 10071769648 10471165952 1107749616 11.911 100 79 158 2 1197700800 74530672 15420512
05:28:51 2567 8192000000 10053719568 10439163904 1094896960 11.789 100 87 165 1 1172196544 59927856 17371728
05:29:54 1323 8192000000 10063834928 10449399808 1085842720 11.703 100 97 193 2 1186893680 81138688 19912272
05:30:56 1254 8192000000 10021396480 10396868608 1054590224 11.405 100 95 180 0 1142095408 68106592 19398592
05:31:58 1300 8192000000 10015644752 10393923584 1050284032 11.363 100 97 180 2 1136864960 68641792 17939136
05:33:02 1299 8192000000 10005755856 10379059200 1042402160 11.288 98 96 177 1 1123377728 62324192 18651376
05:34:04 949 8192000000 9991753776 10368167936 1042283344 11.287 100 88 160 4 1116215648 57282784 16649520
05:35:06 1109 8192000000 9990618768 10364194816 1042114416 11.285 100 93 177 0 1114200512 53815808 18270288
05:36:08 2460 8192000000 10004562176 10389110784 1037783488 11.243 100 77 147 0 1134004784 80649920 15571376
05:37:11 1166 8192000000 9979149856 10352328704 1041637168 11.280 100 89 169 4 1089348800 31247328 16464304
05:38:22 1448 8192000000 10027682384 10403164160 1058254320 11.440 100 78 145 3 1136073104 64772944 13045840
05:39:30 8097 8192000000 10047211696 10434547712 1082207664 11.669 100 71 138 0 1175228704 78463408 14557632
05:40:41 1879 8192000000 10062645168 10450690048 1086034688 11.705 99 74 143 2 1182912400 80304656 16573056
05:41:46 1576 8192000000 10107537424 10496348160 1104137120 11.877 100 77 144 0 1222130592 101930848 16062624
05:42:52 1630 8192000000 10137997424 10530783232 1125603040 12.080 100 87 163 0 1258490880 114861344 18026496
05:44:10 994 8192000000 10176385216 10567626752 1129680160 12.118 100 79 154 2 1291977072 145833616 16463296
05:45:17 2299 8192000000 10209336784 10605465600 1140016320 12.216 100 86 157 2 1318193840 161411856 16765664
05:46:24 1123 8192000000 10252669808 10647801856 1153729248 12.344 100 93 175 3 1363823264 190141344 19952672
05:47:27 956 8192000000 10286933280 10685210624 1167452864 12.473 100 89 174 2 1398174976 210294144 20427968
05:48:33 2088 8192000000 10308198816 10708611072 1169683984 12.494 100 89 170 0 1422505344 234475328 18346032
05:49:38 993 8192000000 10378074704 10777731072 1179228320 12.583 100 95 185 0 1488607344 287454608 21924416
05:50:42 1406 8192000000 10405547088 10813804544 1175121856 12.545 100 89 173 0 1504322432 309219216 19981360
05:51:46 2083 8192000000 10435623488 10854293504 1183473376 12.623 100 95 186 0 1547993280 341957616 22562288
05:52:52 1699 8192000000 10456566784 10880196608 1197578400 12.754 100 80 154 0 1561086880 345371824 18136656
05:53:57 1548 8192000000 10456500048 10897317888 1192117296 12.703 100 90 172 0 1560410048 347995952 20296800
05:55:01 3183 8192000000 10506404816 10928599040 1183247088 12.620 99 74 148 0 1607917312 406228512 18441712
05:56:08 3052 8192000000 10537584848 10983796736 1196937872 12.748 100 75 150 0 1645072368 429360096 18774400
05:57:17 1401 8192000000 10535116944 10987589632 1190369584 12.687 100 92 181 0 1639350896 426980496 22000816
05:58:22 1617 8192000000 10544554048 10982465536 1193743296 12.718 100 98 190 1 1646153920 429452432 22958192
05:59:29 4588 8192000000 10549579632 10999885824 1204539664 12.818 100 75 143 0 1667193904 445640864 17013376
06:00:36 3583 8192000000 10567341840 11009662976 1193163376 12.713 100 59 113 1 1672619456 465370528 14085552
06:01:45 6074 8192000000 10566713040 11011198976 1181366032 12.603 100 62 123 0 1683226224 486197440 15662752
06:03:05 5590 8192000000 10565401328 11025199104 1170041872 12.497 99 58 113 2 1656541424 472596224 13903328
06:04:20 7325 8192000000 10573512288 11044007936 1166260368 12.462 99 48 94 1 1668498688 489858896 12379424
06:05:29 18122 8192000000 10494007184 10986942464 1172788016 12.523 100 42 84 0 1605314368 421281488 11244864
06:06:49 21695 8192000000 10433197824 10983030784 1159835536 12.402 100 22 44 0 1533044400 367105232 6103632
06:08:18 32764 8192000000 10336774816 10891055104 1140011984 12.216 99 21 42 2 1493585184 348105984 5467216
06:10:13 22128 8192000000 10118596816 10593992704 1114699024 11.977 98 25 49 0 1230071152 110181616 5190512
06:11:55 72074 8192000000 10110641456 10546618368 1135435040 12.173 99 59 104 0 1219378624 72695824 11247760
06:14:14 41237 8192000000 10085727200 10573230080 1138709056 12.203 99 12 24 1 1198931680 57893056 2329568
06:16:01 95622 8192000000 10106974240 10550009856 1125606512 12.080 98 23 46 0 1183333504 52848240 4878752
06:18:43 129131 8192000000 10109941088 10595196928 1108708960 11.920 97 17 33 4 1190440560 78239696 3491904
06:22:27 126774 8192000000 10098405248 10548596736 1101296848 11.850 97 20 40 1 1202438704 95728448 5413408
06:25:39 142446 8192000000 10046742240 10538618880 1111926336 11.951 98 25 49 1 1176732256 59101936 5703984
06:29:02 26621 8192000000 10055586736 10525380608 1121035024 12.037 100 17 34 0 1159297600 34701984 3560592
06:30:42 29504 8192000000 10072033808 10539667456 1132335648 12.143 100 39 78 1 1195199392 55043440 7820304
06:32:18 45988 8192000000 10068054160 10520363008 1124638704 12.071 99 23 44 1 1160497776 31775280 4083792
06:34:04 53890 8192000000 10052246528 10524790784 1105898960 11.894 97 10 20 0 1161843024 53623904 2320160
06:35:59 63699 8192000000 10042344944 10490920960 1112999568 11.961 99 25 51 1 1156768368 37885088 5883712
06:38:15 26007 8192000000 10077081216 10527551488 1125799840 12.082 99 29 53 1 1176594336 45439232 5355264
06:39:43 66363 8192000000 10045546032 10496421888 1117163328 12.000 98 10 20 0 1139645120 20699200 1782592
06:42:00 35973 8192000000 10088720592 10541420544 1126692800 12.090 99 39 78 1 1209154432 74164528 8297104
06:43:41 15189 8192000000 10140492096 10573463552 1134895168 12.167 99 66 126 1 1226849408 78246400 13707840
06:45:05 61150 8192000000 10090247200 10538303488 1112456048 11.956 97 27 54 1 1208741184 90482704 5802432
06:47:09 40910 8192000000 10079564672 10537136128 1111013760 11.942 98 19 38 1 1171096272 56259856 3822656
06:48:50 26306 8192000000 10084779872 10533376000 1115740816 11.987 99 49 93 1 1179299456 54187184 9371456
06:50:19 42858 8192000000 10123041744 10566946816 1110713568 11.939 98 44 88 1 1187487344 68175856 8597920
06:52:06 52311 8192000000 10141130240 10595115008 1111587424 11.947 99 30 59 1 1232080544 113856688 6636432
06:54:02 64094 8192000000 10034618608 10530209792 1078598848 11.634 97 11 22 0 1203417008 122107056 2711104
06:56:46 39052 8192000000 10050494928 10478706688 1114064528 11.971 100 46 90 0 1154717824 32130112 8523184
06:58:28 104367 8192000000 10083719568 10549305344 1106814224 11.902 98 20 40 1 1191249552 79812352 4622976
07:02:04 79057 8192000000 10061384112 10552995840 1111540144 11.947 99 4 8 0 1177233600 64962816 730640
07:04:31 70873 8192000000 10081013504 10524639232 1074552656 11.596 96 15 30 1 1137716720 59833232 3330832
07:06:42 57402 8192000000 10068296432 10530963456 1116137408 11.990 99 35 69 2 1172483808 49730576 6615824
07:08:41 29168 8192000000 10089379712 10530058240 1106997248 11.904 98 42 84 1 1193893760 78744720 8151792
07:10:18 28041 8192000000 10106789952 10540638208 1106190976 11.896 98 40 79 1 1179356112 65262736 7902400
07:11:46 19671 8192000000 10074447504 10498191360 1123819120 12.063 100 46 91 2 1180771888 48192688 8760080
07:13:10 28737 8192000000 10126260752 10558488576 1112554304 11.957 98 36 72 0 1210948928 91489648 6904976
07:14:38 42161 8192000000 10089362448 10524999680 1115001984 11.980 99 32 64 1 1198296256 76589760 6704512
07:17:33 107029 8192000000 10091325376 10536394752 1114557008 11.976 99 50 100 0 1192207136 67483776 10166352
07:20:21 29189 8192000000 10058273152 10509180928 1107195344 11.906 98 20 39 1 1183027536 71616544 4215648
07:22:06 47070 8192000000 10061889376 10525741056 1104235888 11.878 98 9 17 1 1150897792 44904384 1757520
07:24:18 57034 8192000000 10053963904 10510045184 1080621136 11.653 96 14 28 0 1143031296 59162224 3247936
07:26:16 74562 8192000000 10067696480 10508713984 1081412464 11.661 96 14 28 0 1112341248 28277424 2651360
07:28:37 38472 8192000000 10124304464 10577219584 1118642080 12.014 99 50 100 1 1224093344 95009824 10441440
07:30:18 54472 8192000000 10086944768 10557460480 1127822880 12.101 100 29 57 1 1191323552 57889280 5611392
07:32:16 55799 8192000000 10119115152 10584936448 1127976416 12.102 99 29 58 1 1202422176 68823152 5622608
07:34:15 129469 8192000000 10069937072 10552008704 1076401328 11.613 95 9 18 0 1152266432 73678176 2186928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-05-2022 07:40:06 to 04-05-2022 07:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-05-2022 07:40:23 to 04-05-2022 07:40:25.

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          217835
MON$OLDEST_ACTIVE               219192
MON$OLDEST_SNAPSHOT             219192
MON$NEXT_TRANSACTION            219192
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                       2056096
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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 ---
:110: 
:111: mscs-test-161.budzdorov.ru    Wed May  4 07:38:08 2022
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:113: 
--- end of diff output ---

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

04.05.22 07:40:26. 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-04 07:40:29 No severe PSQL-related problems occured
04.05.22 07:40:30. End of report.