Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14609344 kB
MemAvailable 30132112 kB
Buffers 0 kB
Cached 17114048 kB
SwapCached 3360 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.3
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-12-27 10:37:23
DTS_END                         2022-12-27 10:37:23.3780
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/60666
TRN_ID                          217517
ATT_ID                          594
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: 8688.43 31621 1042612
customer order: creation 161.98 464 19438
customer order: refuse 14.39 423 1727
order to supplier: creation 43.83 1282 5259
order to supplier: removal 3.31 160569 397
invoice (draft): creation 45.18 3048 5422
invoice (draft): removal 48.70 1031 5844
invoice accept: apply 40.38 25745 4846
invoice accept: cancel 43.83 1273 5259
customer reserve: creation 3725.46 246 447055
customer reserve: removal 4285.18 97 514222
realization accept: apply 79.20 257 9504
realization accept: cancel 73.58 172 8830
payment from customer: creation 56.51 173 6781
payment from customer: removal 5.14 110 617
payment to supplier: creation 46.53 189 5584
payment to supplier: removal 3.28 132 393
service: total inventory turnovers 3.79 4104 455
service: total monetary turnovers 7.24 428 869
service: aggregate perf. data 0.88 18636 105
service: refresh index statistics 0.04 414048 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2022 10:39:03 to 27-12-2022 10:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8732
TEST_TIME 2 6309
TEST_TIME 3 5711
TEST_TIME 4 5483
TEST_TIME 5 5560
TEST_TIME 6 5610
TEST_TIME 7 5997
TEST_TIME 8 5971
TEST_TIME 9 6117
TEST_TIME 10 6662
TEST_TIME 11 6723
TEST_TIME 12 7070
TEST_TIME 13 7198
TEST_TIME 14 7561
TEST_TIME 15 7670
TEST_TIME 16 7981
TEST_TIME 17 7935
TEST_TIME 18 7936
TEST_TIME 19 8000
TEST_TIME 20 8386
TEST_TIME 21 8554
TEST_TIME 22 8827
TEST_TIME 23 8952
TEST_TIME 24 8707
TEST_TIME 25 8681
TEST_TIME 26 8675
TEST_TIME 27 8762
TEST_TIME 28 8970
TEST_TIME 29 9598
TEST_TIME 30 9769
TEST_TIME 31 9905
TEST_TIME 32 10342
TEST_TIME 33 10217
TEST_TIME 34 10610
TEST_TIME 35 10610
TEST_TIME 36 11080
TEST_TIME 37 11278
TEST_TIME 38 11011
TEST_TIME 39 10780
TEST_TIME 40 10680
TEST_TIME 41 10631
TEST_TIME 42 10553
TEST_TIME 43 10477
TEST_TIME 44 10361
TEST_TIME 45 10256
TEST_TIME 46 10123
TEST_TIME 47 9984
TEST_TIME 48 10075
TEST_TIME 49 10051
TEST_TIME 50 9887
TEST_TIME 51 9706
TEST_TIME 52 9596
TEST_TIME 53 9700
TEST_TIME 54 9635
TEST_TIME 55 9573
TEST_TIME 56 9569
TEST_TIME 57 9602
TEST_TIME 58 9496
TEST_TIME 59 9447
TEST_TIME 60 9426
TEST_TIME 61 9568
TEST_TIME 62 9602
TEST_TIME 63 9735
TEST_TIME 64 9665
TEST_TIME 65 9680
TEST_TIME 66 9691
TEST_TIME 67 9624
TEST_TIME 68 9527
TEST_TIME 69 9469
TEST_TIME 70 9466
TEST_TIME 71 9453
TEST_TIME 72 9386
TEST_TIME 73 9315
TEST_TIME 74 9243
TEST_TIME 75 9218
TEST_TIME 76 9255
TEST_TIME 77 9242
TEST_TIME 78 9222
TEST_TIME 79 9236
TEST_TIME 80 9221
TEST_TIME 81 9205
TEST_TIME 82 9211
TEST_TIME 83 9242
TEST_TIME 84 9243
TEST_TIME 85 9290
TEST_TIME 86 9250
TEST_TIME 87 9296
TEST_TIME 88 9280
TEST_TIME 89 9291
TEST_TIME 90 9256
TEST_TIME 91 9182
TEST_TIME 92 9128
TEST_TIME 93 9117
TEST_TIME 94 9132
TEST_TIME 95 9068
TEST_TIME 96 9026
TEST_TIME 97 9019
TEST_TIME 98 8985
TEST_TIME 99 8947
TEST_TIME 100 8926
TEST_TIME 101 8948
TEST_TIME 102 8947
TEST_TIME 103 8950
TEST_TIME 104 8940
TEST_TIME 105 8977
TEST_TIME 106 8935
TEST_TIME 107 9012
TEST_TIME 108 8975
TEST_TIME 109 8952
TEST_TIME 110 9030
TEST_TIME 111 9005
TEST_TIME 112 9014
TEST_TIME 113 8946
TEST_TIME 114 8921
TEST_TIME 115 8867
TEST_TIME 116 8846
TEST_TIME 117 8816
TEST_TIME 118 8791
TEST_TIME 119 8774
TEST_TIME 120 8715

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2022 10:39:07 to 27-12-2022 10:39:10.

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 1454257 1454257 0 0.00 0 25957 13 0 0
doc_list_biud 1454238 1454238 0 0.00 0 69664 40 0 0
sp_add_invoice_to_stock 4923 4846 77 1.56 23 235442 25746 0 77
sp_cancel_adding_invoice 5259 5259 0 0.00 7 67472 1274 0 0
sp_cancel_client_order 1727 1727 0 0.00 6 12452 423 0 0
sp_cancel_customer_reserve 514222 514222 0 0.00 1 41097 97 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 4 1466 110 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 5511 132 0 0
sp_cancel_supplier_invoice 5851 5844 7 0.11 3 69740 1031 0 7
sp_cancel_supplier_order 397 397 0 0.00 9 1394701 160569 0 0
sp_cancel_write_off 8830 8830 0 0.00 2 13478 173 0 0
sp_client_order 19438 19438 0 0.00 6 43276 465 0 0
sp_customer_reserve 447055 447055 0 0.00 0 61347 247 0 0
sp_fill_shopping_cart 30107 30107 0 0.00 1 15750 83 0 0
sp_fill_shopping_cart_clo_res 447018 447018 0 0.00 0 13560 19 0 0
sp_get_clo_for_invoice 4796 4796 0 0.00 0 25565 315 0 0
sp_kill_cost_storno 15099 15099 0 0.00 0 5148 74 0 0
sp_kill_qstorno_ret_qs2qd 1043515 1043515 0 0.00 0 69663 43 0 0
sp_kill_qty_storno 550579 550579 0 0.00 0 69663 98 0 0
sp_lock_selected_doc 19896 19896 0 0.00 0 7560 6 0 0
sp_make_cost_storno 26665 26665 0 0.00 0 11845 73 0 0
sp_multiply_rows_for_pdistr 26664 26664 0 0.00 0 10882 37 0 0
sp_multiply_rows_for_qdistr 19438 19438 0 0.00 0 43235 178 0 0
sp_pay_from_customer 6781 6781 0 0.00 6 19187 173 0 0
sp_pay_to_supplier 5584 5584 0 0.00 6 8829 189 0 0
sp_payment_common 12363 12363 0 0.00 2 12867 50 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 1 12444 354 0 0
sp_qd_handle_on_invoice_upd_sts 10055 10055 0 0.00 0 37946 529 0 0
sp_qd_handle_on_reserve_upd_sts 9504 9504 0 0.00 0 13499 57 0 0
sp_reserve_write_off 9504 9504 0 0.00 8 17159 257 0 0
sp_supplier_invoice 5422 5422 0 0.00 9 90156 3049 0 0
sp_supplier_order 5259 5259 0 0.00 6 37989 1282 0 0
srv_aggregate_perf_data 105 105 0 0.00 2456 254735 18637 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 753 142478 34803 0 0
srv_find_qd_qs_mism 995743 995743 0 0.00 0 25922 10 0 0
srv_make_invnt_saldo 455 455 0 0.00 45 111454 4104 0 0
srv_make_money_saldo 869 869 0 0.00 1 20243 429 0 0
srv_recalc_idx_stat 5 5 0 0.00 277266 488461 414048 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4847 4847 0 0.00 0 30257 903 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 2 4536 458 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 1789 26 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 2843 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 663 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 351 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1105 32 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 103924 7254 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 1207 17 0 0
t$perf-norm:sp_client_order 19438 19438 0 0.00 0 11159 35 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 954 14 0 0
t$perf-norm:sp_pay_from_customer 6781 6781 0 0.00 0 4069 18 0 0
t$perf-norm:sp_pay_to_supplier 5584 5584 0 0.00 0 9406 20 0 0
t$perf-norm:sp_reserve_write_off 9504 9504 0 0.00 0 4118 18 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 3078 26 0 0
t$perf-norm:sp_supplier_order 5259 5259 0 0.00 0 1275 29 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 188 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 85 6 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 939 17 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 632 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 198 71 0 0
v_add_invoice_to_stock 9738 9584 154 1.58 0 5342 24 0 154
v_all_customers 35821 35821 0 0.00 0 64 4 0 0
v_all_suppliers 29766 29766 0 0.00 0 81 4 0 0
v_all_wares 38876 38876 0 0.00 0 67 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 610 15 0 0
v_cancel_client_order 3454 3454 0 0.00 0 2485 13 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 216 10 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 444 11 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 1389 12 0 0
v_cancel_supplier_invoice 772 758 14 1.81 0 7711 35 0 14
v_cancel_supplier_order 794 794 0 0.00 0 1488 11 0 0
v_cancel_write_off 1972 1972 0 0.00 0 1682 23 0 0
v_max_id_clo_ord 5253 5253 0 0.00 0 3157 6 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 4644 19 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 9665 9 0 0
v_max_non_paid_invoice 1367 1367 0 0.00 0 66 7 0 0
v_max_non_paid_realizn 3486 3486 0 0.00 0 1591 8 0 0
v_min_id_clo_ord 5253 5253 0 0.00 0 1178 8 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 3560 14 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 8535 9 0 0
v_min_non_paid_invoice 5582 5582 0 0.00 0 8499 21 0 0
v_min_non_paid_realizn 6780 6780 0 0.00 0 12862 23 0 0
v_our_firm 9642 9642 0 0.00 0 72 4 0 0
v_reserve_write_off 19006 19006 0 0.00 0 2762 10 0 0
x_lock_depdocs_on_canc_invoice 10724 10724 0 0.00 0 20362 141 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 17975 384 0 0
x_make_qstorno_1000_1200 5259 5259 0 0.00 2 37952 1111 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 4 90140 2822 0 0
x_make_qstorno_2100_3300 447054 447054 0 0.00 0 61308 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2022 10:39:11 to 27-12-2022 10:39:15.

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
08:07:27 3 8192000000 8799438000 8810778624 4463008 0.054 2 1 2 0 4562400 63616 35776
08:08:27 27669 8192000000 9705042512 9997950976 776258208 8.655 100 87 174 0 805611168 7852688 21500272
08:10:07 19715 8192000000 9748246176 10051784704 822912592 9.128 100 92 184 0 858131216 12602336 22616288
08:11:30 17675 8192000000 9786657520 10101583872 850380928 9.404 100 80 160 0 890132208 20744432 19006848
08:13:10 7645 8192000000 9859373424 10190348288 911164528 10.009 100 84 166 0 972316096 42668496 18483072
08:14:24 3045 8192000000 9916045952 10258911232 958292608 10.472 100 91 170 0 1031785520 55329680 18163232
08:15:30 1600 8192000000 9955652128 10310635520 993482208 10.815 100 82 158 0 1075824944 66335520 16007216
08:16:34 1366 8192000000 9999025760 10360115200 1022370496 11.095 100 92 176 0 1112930048 72896880 17662672
08:17:38 3054 8192000000 9998027104 10377515008 1049995936 11.361 100 81 154 1 1129506880 64651552 14859392
08:18:42 1197 8192000000 10036866960 10410631168 1067698448 11.530 100 98 186 1 1158289760 70645920 19945392
08:19:45 1865 8192000000 10024299968 10399129600 1069833456 11.550 100 83 159 0 1143747376 58688944 15224976
08:20:47 1905 8192000000 10039479728 10425724928 1079511312 11.643 100 79 150 1 1166128128 71470624 15146192
08:21:50 1133 8192000000 10066325232 10451865600 1091510464 11.757 100 94 181 2 1188130336 78302096 18317776
08:22:52 1241 8192000000 10098353088 10486517760 1093448688 11.775 100 95 191 1 1219526080 106819840 19257552
08:23:54 1187 8192000000 10065900976 10452287488 1094979664 11.790 100 98 190 1 1189168592 74842288 19346640
08:24:56 1046 8192000000 10053341440 10438287360 1096993728 11.809 100 94 183 3 1176045712 61681888 17370096
08:25:58 3775 8192000000 10057214944 10450800640 1094484176 11.785 100 72 134 2 1171811760 64068880 13258704
08:27:03 1148 8192000000 10057148384 10446155776 1099114160 11.829 100 97 183 4 1179448048 60961088 19372800
08:28:05 2159 8192000000 10054000160 10448400384 1099576560 11.834 100 72 140 0 1170629280 56758016 14294704
08:29:08 3850 8192000000 10034125536 10425520128 1083859056 11.684 100 75 143 2 1153629328 56095776 13674496
08:30:12 1985 8192000000 10038169792 10431709184 1088792944 11.731 100 84 152 2 1156469888 51819728 15857216
08:31:15 1042 8192000000 10039038288 10423377920 1075302656 11.603 100 90 170 4 1164950096 72335152 17312288
08:32:17 1078 8192000000 10028201024 10401480704 1054047680 11.399 100 94 184 0 1148808464 75926400 18834384
08:33:19 889 8192000000 9980493552 10356011008 1026402096 11.134 100 85 159 1 1102870992 60824912 15643984
08:34:21 923 8192000000 9975792128 10351312896 1043106992 11.295 100 88 169 2 1096352464 36201888 17043584
08:35:23 1061 8192000000 9963469680 10332848128 1015960256 11.033 98 89 168 3 1087719456 54420192 17339008
08:36:24 7190 8192000000 10018291936 10395754496 1037732224 11.243 100 71 135 1 1132898656 81655056 13511376
08:37:36 25406 8192000000 9993699712 10380738560 1042840592 11.292 100 55 105 2 1124971648 71808976 10322080
08:39:09 2372 8192000000 10003397840 10384154624 1059755648 11.454 100 75 142 1 1124086176 50220448 14110080
08:40:43 1764 8192000000 10053858304 10429423616 1066359680 11.517 99 85 160 0 1174429888 91859664 16210544
08:41:49 16672 8192000000 10098931984 10496688128 1083112704 11.677 100 84 155 1 1208078528 109059728 15906096
08:43:11 10637 8192000000 10132136048 10531184640 1099540176 11.833 100 69 130 1 1255813360 142078544 14194640
08:44:26 852 8192000000 10141723744 10540617728 1109294304 11.926 100 79 142 3 1258639232 134042320 15302608
08:45:32 1256 8192000000 10178197296 10565726208 1115973280 11.989 99 83 161 2 1290029968 157184016 16872672
08:46:37 1787 8192000000 10214724640 10605879296 1133714544 12.156 100 97 186 1 1328950304 173551200 21684560
08:47:41 2291 8192000000 10228504544 10629332992 1153642032 12.344 100 87 165 0 1349174160 177303840 18228288
08:48:45 2678 8192000000 10275947520 10686730240 1160457408 12.408 100 91 166 1 1392853664 214524800 17871456
08:49:50 851 8192000000 10305706608 10709995520 1171217072 12.508 100 93 177 1 1418346496 226105152 21024272
08:50:54 1583 8192000000 10291843936 10716819456 1173426064 12.529 100 93 180 4 1414744512 220125056 21193392
08:51:58 1468 8192000000 10312650368 10716348416 1174201504 12.536 99 87 165 0 1421687264 228594960 18890800
08:53:01 891 8192000000 10346215360 10760093696 1187943920 12.664 100 96 187 1 1452993472 243072080 21977472
08:54:05 745 8192000000 10361333744 10765139968 1184732384 12.634 100 94 186 0 1466423552 261384352 20306816
08:55:11 986 8192000000 10432095232 10840555520 1197709248 12.755 100 97 182 1 1538839328 319836528 21293552
08:56:15 865 8192000000 10443872192 10873401344 1201085376 12.786 100 96 192 0 1549278912 325899728 22293808
08:57:20 910 8192000000 10405372640 10870353920 1187595136 12.661 100 92 178 0 1511975792 302822928 21557728
08:58:24 2724 8192000000 10422404592 10854113280 1180828064 12.598 100 87 168 0 1530193600 329270720 20094816
08:59:28 5640 8192000000 10403851824 10857922560 1191632112 12.699 100 42 82 1 1522833536 320284800 10916624
09:00:48 6547 8192000000 10402961104 10838380544 1191266336 12.695 100 48 94 0 1503837600 301204736 11366528
09:02:05 9586 8192000000 10470213120 10914652160 1185785104 12.644 100 76 144 2 1573942768 371483600 16674064
09:03:19 4798 8192000000 10485152816 10925490176 1167972800 12.478 100 51 102 0 1589343648 407772368 13598480
09:04:27 7358 8192000000 10504737040 10930507776 1170029920 12.497 100 64 126 0 1611700464 425899600 15770944
09:05:44 2935 8192000000 10435601808 10929201152 1155812560 12.364 99 77 155 1 1531228032 356577296 18838176
09:06:49 12015 8192000000 10380287280 10878263296 1165668432 12.456 100 39 78 0 1534011456 358561072 9781952
09:08:09 5210 8192000000 10406409248 10865799168 1179257264 12.583 100 36 72 0 1514493968 326102576 9134128
09:09:26 20632 8192000000 10278783744 10747236352 1156644800 12.372 99 31 59 0 1395970944 232299248 7026896
09:10:50 16833 8192000000 10332639552 10807091200 1141119616 12.226 100 37 74 0 1436083056 285773424 9190016
09:12:14 28711 8192000000 10268176176 10785370112 1144281344 12.256 100 20 40 0 1379014976 229804576 4929056
09:14:13 95587 8192000000 10077409136 10546827264 1113289392 11.964 99 24 45 0 1196205616 77737872 5178352
09:17:13 59725 8192000000 10081280224 10536464384 1087440240 11.718 97 40 73 1 1159139808 64349872 7349696
09:19:13 50629 8192000000 10077601440 10555105280 1110844832 11.940 99 39 76 2 1170714144 50907184 8962128
09:21:52 93718 8192000000 10101965696 10566836224 1125814944 12.082 100 15 29 0 1195092880 66514032 2763904
09:24:50 142463 8192000000 10084434480 10534391808 1112577856 11.957 97 16 32 0 1226584880 110200384 3806640
09:28:13 58545 8192000000 10126584432 10583523328 1138159936 12.198 100 16 32 0 1214814128 73390352 3263840
09:30:13 70779 8192000000 10064951072 10545889280 1143206880 12.246 100 5 10 0 1208467904 64074672 1186352
09:32:27 80407 8192000000 10119374128 10580750336 1139601360 12.212 99 13 26 0 1199366768 57012448 2752960
09:35:05 43685 8192000000 10144024464 10588532736 1158430608 12.389 100 30 58 0 1222326736 57782656 6113472
09:37:12 42669 8192000000 10198837552 10681196544 1151812352 12.327 100 23 46 0 1288081456 130788464 5480640
09:39:02 74981 8192000000 10113597296 10610327552 1113339072 11.964 97 14 28 0 1240863536 123713024 3811440
09:41:34 56201 8192000000 10099679680 10578874368 1115725120 11.987 98 24 48 0 1182706608 62107488 4874000
09:43:36 16492 8192000000 10103544032 10535194624 1125850400 12.082 98 50 98 0 1184485136 48203664 10431072
09:44:53 78735 8192000000 10105935376 10563923968 1118976400 12.017 98 33 66 1 1187350944 61987568 6386976
09:47:22 2538 8192000000 10108210592 10559344640 1149909408 12.309 100 41 71 1 1210595760 54041584 6644768
09:48:27 59430 8192000000 10076839840 10566090752 1132722944 12.147 99 14 28 0 1185792976 50187488 2882544
09:50:27 51791 8192000000 10116383264 10569998336 1121418896 12.040 98 12 24 0 1186633840 62596736 2618208
09:52:36 84791 8192000000 10066796224 10544861184 1126123168 12.085 99 11 22 0 1171603904 43180960 2299776
09:55:02 29657 8192000000 10079590000 10525720576 1144266752 12.256 100 24 46 0 1177697104 29162656 4267696
09:56:39 46156 8192000000 10094089088 10550255616 1106949296 11.904 97 25 50 0 1162365520 50468928 4947296
09:58:25 30295 8192000000 10126642848 10591223808 1114744384 11.977 97 49 97 0 1189813952 65234096 9835472
09:59:56 11379 8192000000 10152791936 10592083968 1132811264 12.148 99 39 76 0 1242522288 101647088 8063936
10:01:09 34022 8192000000 10146157024 10584723456 1120120592 12.028 98 35 68 0 1221254160 94290288 6843280
10:02:52 14660 8192000000 10093149168 10515091456 1125844512 12.082 100 45 87 1 1182333168 48274816 8213840
10:04:12 39872 8192000000 10115502096 10599415808 1130977680 12.131 100 50 99 0 1238949360 97411328 10560352
10:06:02 36218 8192000000 10092132544 10518843392 1083291104 11.679 96 37 74 0 1150554352 59804064 7459184
10:07:41 108374 8192000000 10100768432 10552958976 1124366512 12.068 100 49 98 1 1208843680 74441152 10036016
10:10:30 21267 8192000000 10073875008 10529677312 1134349680 12.162 100 41 77 1 1176887392 33775776 8761936
10:11:56 45816 8192000000 10045754336 10522980352 1108147744 11.915 98 12 19 0 1139172528 29153120 1871664
10:13:42 64190 8192000000 10051229392 10534764544 1120368688 12.030 99 15 30 0 1176311408 52769120 3173600
10:16:03 40216 8192000000 10058310528 10515906560 1113817104 11.969 99 19 36 0 1144764208 27196560 3750544
10:18:05 36723 8192000000 10106681440 10570055680 1136997696 12.187 100 31 61 0 1206924992 63442640 6484656
10:19:42 38631 8192000000 10112925392 10561613824 1138096640 12.198 100 29 58 0 1200959616 56183408 6679568
10:21:31 35706 8192000000 10095742880 10563026944 1119593504 12.023 98 27 54 0 1177819072 52656496 5569072
10:23:07 63483 8192000000 10138446448 10595250176 1130154080 12.123 99 30 59 0 1216254432 80212240 5888112
10:25:30 44223 8192000000 10090089744 10534789120 1110420512 11.936 97 40 80 1 1146213216 28162656 7630048
10:27:18 70284 8192000000 10085185920 10526273536 1126608304 12.089 99 16 32 1 1171979456 42454512 2916640
10:29:33 113740 8192000000 10075837648 10523422720 1098485888 11.823 97 13 26 0 1140186928 39106752 2594288
10:32:27 84313 8192000000 10060694096 10559778816 1107889840 11.912 98 7 14 0 1153241168 43818592 1532736
10:34:52 83396 8192000000 10095805648 10549972992 1111823520 11.950 98 17 34 0 1149136160 33955360 3357280
10:37:17 17923 8192000000 8913462624 9088942080 59919920 0.726 6 3 6 0 99830128 39235904 674304

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-12-2022 10:39:17 to 27-12-2022 10:39:33.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2022 10:39:34 to 27-12-2022 10: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          220043
MON$OLDEST_ACTIVE               220044
MON$OLDEST_SNAPSHOT             220044
MON$NEXT_TRANSACTION            220044
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                       2091072
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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.12.22 10:39:38. 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-12-27 10:39:40 No severe PSQL-related problems occured
27.12.22 10:39:41. End of report.