Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.03.2023 10: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 14851420 kB
MemAvailable 30464816 kB
Buffers 0 kB
Cached 16865164 kB
SwapCached 3032 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 2023-03-10 10:37:27
DTS_END                         2023-03-10 10:37:27.5600
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/52716
TRN_ID                          222556
ATT_ID                          643
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: 9335.93 22674 1120312
customer order: creation 175.88 463 21105
customer order: refuse 14.96 368 1795
order to supplier: creation 46.01 1411 5521
order to supplier: removal 3.54 165377 425
invoice (draft): creation 45.78 2906 5494
invoice (draft): removal 51.96 1024 6235
invoice accept: apply 42.22 25775 5066
invoice accept: cancel 46.78 1131 5614
customer reserve: creation 4004.98 241 480598
customer reserve: removal 4607.00 98 552840
realization accept: apply 84.61 275 10153
realization accept: cancel 81.22 167 9747
payment from customer: creation 60.43 178 7251
payment from customer: removal 5.33 141 640
payment to supplier: creation 49.07 188 5888
payment to supplier: removal 3.28 119 393
service: total inventory turnovers 3.99 4391 479
service: total monetary turnovers 7.92 321 950
service: aggregate perf. data 0.95 15747 114
service: refresh index statistics 0.03 233150 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-03-2023 10:39:36 to 10-03-2023 10:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5469
TEST_TIME 2 5033
TEST_TIME 3 5275
TEST_TIME 4 6430
TEST_TIME 5 6217
TEST_TIME 6 6299
TEST_TIME 7 6419
TEST_TIME 8 6432
TEST_TIME 9 6779
TEST_TIME 10 6778
TEST_TIME 11 6946
TEST_TIME 12 6849
TEST_TIME 13 6945
TEST_TIME 14 7393
TEST_TIME 15 7477
TEST_TIME 16 7634
TEST_TIME 17 7851
TEST_TIME 18 7828
TEST_TIME 19 7776
TEST_TIME 20 7804
TEST_TIME 21 7847
TEST_TIME 22 8114
TEST_TIME 23 8094
TEST_TIME 24 8207
TEST_TIME 25 8224
TEST_TIME 26 8045
TEST_TIME 27 8587
TEST_TIME 28 8811
TEST_TIME 29 9074
TEST_TIME 30 9940
TEST_TIME 31 9933
TEST_TIME 32 10356
TEST_TIME 33 10532
TEST_TIME 34 11012
TEST_TIME 35 11609
TEST_TIME 36 11719
TEST_TIME 37 11491
TEST_TIME 38 11323
TEST_TIME 39 11153
TEST_TIME 40 11006
TEST_TIME 41 10990
TEST_TIME 42 10967
TEST_TIME 43 10816
TEST_TIME 44 10776
TEST_TIME 45 10609
TEST_TIME 46 10479
TEST_TIME 47 10570
TEST_TIME 48 10551
TEST_TIME 49 10531
TEST_TIME 50 10593
TEST_TIME 51 10504
TEST_TIME 52 10445
TEST_TIME 53 10305
TEST_TIME 54 10309
TEST_TIME 55 10180
TEST_TIME 56 10141
TEST_TIME 57 10067
TEST_TIME 58 9992
TEST_TIME 59 9898
TEST_TIME 60 9930
TEST_TIME 61 9907
TEST_TIME 62 9965
TEST_TIME 63 9917
TEST_TIME 64 9991
TEST_TIME 65 10031
TEST_TIME 66 9993
TEST_TIME 67 10049
TEST_TIME 68 10063
TEST_TIME 69 10025
TEST_TIME 70 10009
TEST_TIME 71 10069
TEST_TIME 72 10046
TEST_TIME 73 10014
TEST_TIME 74 9997
TEST_TIME 75 9939
TEST_TIME 76 9862
TEST_TIME 77 9870
TEST_TIME 78 9796
TEST_TIME 79 9736
TEST_TIME 80 9670
TEST_TIME 81 9572
TEST_TIME 82 9578
TEST_TIME 83 9542
TEST_TIME 84 9515
TEST_TIME 85 9533
TEST_TIME 86 9477
TEST_TIME 87 9446
TEST_TIME 88 9485
TEST_TIME 89 9507
TEST_TIME 90 9516
TEST_TIME 91 9509
TEST_TIME 92 9504
TEST_TIME 93 9523
TEST_TIME 94 9513
TEST_TIME 95 9498
TEST_TIME 96 9638
TEST_TIME 97 9731
TEST_TIME 98 9635
TEST_TIME 99 9613
TEST_TIME 100 9582
TEST_TIME 101 9538
TEST_TIME 102 9519
TEST_TIME 103 9463
TEST_TIME 104 9411
TEST_TIME 105 9372
TEST_TIME 106 9400
TEST_TIME 107 9360
TEST_TIME 108 9379
TEST_TIME 109 9377
TEST_TIME 110 9342
TEST_TIME 111 9361
TEST_TIME 112 9345
TEST_TIME 113 9315
TEST_TIME 114 9411
TEST_TIME 115 9412
TEST_TIME 116 9461
TEST_TIME 117 9425
TEST_TIME 118 9491
TEST_TIME 119 9429
TEST_TIME 120 9353

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-03-2023 10:39:40 to 10-03-2023 10:39:44.

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 1561121 1561121 0 0.00 0 17140 13 0 0
doc_list_biud 1561109 1561109 0 0.00 0 34322 40 0 0
sp_add_invoice_to_stock 5147 5066 81 1.57 45 186214 25775 0 81
sp_cancel_adding_invoice 5614 5614 0 0.00 6 73446 1132 0 0
sp_cancel_client_order 1795 1795 0 0.00 6 5888 369 0 0
sp_cancel_customer_reserve 552840 552840 0 0.00 1 25473 99 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 4 4229 142 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 3185 120 0 0
sp_cancel_supplier_invoice 6240 6235 5 0.08 3 15984 1025 0 5
sp_cancel_supplier_order 425 425 0 0.00 8 1436651 165377 0 0
sp_cancel_write_off 9747 9747 0 0.00 2 8831 168 0 0
sp_client_order 21105 21105 0 0.00 5 18395 463 0 0
sp_customer_reserve 480598 480598 0 0.00 0 81590 241 0 0
sp_fill_shopping_cart 32101 32101 0 0.00 1 12364 77 0 0
sp_fill_shopping_cart_clo_res 480574 480574 0 0.00 0 14891 17 0 0
sp_get_clo_for_invoice 5036 5036 0 0.00 0 25850 263 0 0
sp_kill_cost_storno 16394 16394 0 0.00 0 8827 68 0 0
sp_kill_qstorno_ret_qs2qd 1122087 1122087 0 0.00 0 25355 42 0 0
sp_kill_qty_storno 591841 591841 0 0.00 0 34322 98 0 0
sp_lock_selected_doc 20916 20916 0 0.00 0 9844 8 0 0
sp_make_cost_storno 28326 28326 0 0.00 0 12685 76 0 0
sp_multiply_rows_for_pdistr 28324 28324 0 0.00 0 8288 39 0 0
sp_multiply_rows_for_qdistr 21105 21105 0 0.00 0 10491 172 0 0
sp_pay_from_customer 7251 7251 0 0.00 5 17515 178 0 0
sp_pay_to_supplier 5888 5888 0 0.00 7 16602 189 0 0
sp_payment_common 13138 13138 0 0.00 2 8210 49 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 1 4283 290 0 0
sp_qd_handle_on_invoice_upd_sts 10647 10647 0 0.00 0 34321 532 0 0
sp_qd_handle_on_reserve_upd_sts 10151 10151 0 0.00 0 19726 68 0 0
sp_reserve_write_off 10153 10153 0 0.00 8 19760 276 0 0
sp_supplier_invoice 5494 5494 0 0.00 9 99766 2906 0 0
sp_supplier_order 5521 5521 0 0.00 7 27349 1412 0 0
srv_aggregate_perf_data 114 114 0 0.00 2304 121935 15748 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 771 115759 32361 0 0
srv_find_qd_qs_mism 1069404 1069404 0 0.00 0 12112 10 0 0
srv_make_invnt_saldo 479 479 0 0.00 47 177581 4392 0 0
srv_make_money_saldo 950 950 0 0.00 1 12865 321 0 0
srv_recalc_idx_stat 4 4 0 0.00 93945 327843 233150 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5074 5074 0 0.00 1 10843 945 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 1 9239 465 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 5923 30 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 4055 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 6659 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 335 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 932 35 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 96929 6605 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 645 17 0 0
t$perf-norm:sp_client_order 21105 21105 0 0.00 0 7157 37 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 7261 22 0 0
t$perf-norm:sp_pay_from_customer 7253 7253 0 0.00 0 4266 18 0 0
t$perf-norm:sp_pay_to_supplier 5888 5888 0 0.00 0 2057 17 0 0
t$perf-norm:sp_reserve_write_off 10154 10154 0 0.00 0 10300 26 0 0
t$perf-norm:sp_supplier_invoice 5495 5495 0 0.00 0 6192 32 0 0
t$perf-norm:sp_supplier_order 5521 5521 0 0.00 0 9605 35 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 537 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 25 3 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 772 15 0 0
t$perf-norm:srv_make_money_saldo 950 950 0 0.00 0 5472 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 204 63 0 0
v_add_invoice_to_stock 10220 10058 162 1.58 0 7303 24 0 162
v_all_customers 38832 38832 0 0.00 0 61 4 0 0
v_all_suppliers 30912 30912 0 0.00 0 218 4 0 0
v_all_wares 42198 42198 0 0.00 0 98 4 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 808 16 0 0
v_cancel_client_order 3590 3590 0 0.00 0 3477 13 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 3674 17 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 1 2262 12 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 3111 18 0 0
v_cancel_supplier_invoice 788 778 10 1.26 0 1365 24 0 10
v_cancel_supplier_order 850 850 0 0.00 0 565 11 0 0
v_cancel_write_off 2050 2050 0 0.00 0 1962 24 0 0
v_max_id_clo_ord 5515 5515 0 0.00 0 3464 5 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 1904 20 0 0
v_max_id_ord_sup 5484 5484 0 0.00 0 2342 6 0 0
v_max_non_paid_invoice 1428 1428 0 0.00 0 1148 9 0 0
v_max_non_paid_realizn 3718 3718 0 0.00 0 281 7 0 0
v_min_id_clo_ord 5515 5515 0 0.00 0 2330 8 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 4113 16 0 0
v_min_id_ord_sup 5484 5484 0 0.00 0 7219 10 0 0
v_min_non_paid_invoice 5887 5887 0 0.00 0 7692 20 0 0
v_min_non_paid_realizn 7251 7251 0 0.00 0 8204 18 0 0
v_our_firm 10432 10432 0 0.00 0 98 5 0 0
v_reserve_write_off 20298 20298 0 0.00 0 3108 11 0 0
x_lock_depdocs_on_canc_invoice 11460 11460 0 0.00 0 11468 121 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 19629 383 0 0
x_make_qstorno_1000_1200 5521 5521 0 0.00 3 27339 1259 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 2 98739 2699 0 0
x_make_qstorno_2100_3300 480598 480598 0 0.00 0 81582 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-03-2023 10:39:45 to 10-03-2023 10:39:49.

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:31 892 8192000000 8930179344 8987377664 23974608 0.291 6 4 5 0 24886224 166176 745440
08:08:34 28913 8192000000 9734607008 10036588544 813643872 9.034 100 88 176 0 842551856 7885984 21022000
08:10:05 14982 8192000000 9783177840 10092310528 850182656 9.402 100 93 186 0 884110128 12099648 21827824
08:11:23 16696 8192000000 9804415440 10125307904 875518560 9.655 100 90 180 0 915655232 19363904 20772768
08:12:51 10370 8192000000 9875438960 10210283520 925043072 10.146 100 85 164 0 984284464 41161248 18080144
08:14:06 5905 8192000000 9929807456 10270560256 958982752 10.479 100 87 172 0 1035623936 58569136 18072048
08:15:15 2202 8192000000 9963022944 10316025856 987530928 10.757 100 92 180 0 1087148016 80580400 19036688
08:16:20 1084 8192000000 10003645840 10363953152 1019872656 11.071 100 95 187 0 1121117872 82484656 18760560
08:17:23 1199 8192000000 10004591024 10373054464 1038614272 11.251 100 94 181 0 1121744208 66154768 16975168
08:18:25 1007 8192000000 10022085776 10397868032 1055722208 11.416 100 94 172 4 1143112704 68677200 18713296
08:19:27 1154 8192000000 10051198864 10429308928 1068327792 11.536 100 94 181 2 1169285824 82747200 18210832
08:20:30 877 8192000000 10046464352 10430615552 1073112320 11.582 100 92 181 0 1168341536 77309664 17919552
08:21:32 1111 8192000000 10041886544 10425319424 1074940864 11.599 100 94 179 2 1166522112 73685552 17895696
08:22:34 1293 8192000000 10038272512 10423791616 1077352416 11.622 100 87 170 1 1161094640 66145888 17596336
08:23:38 2607 8192000000 10019746800 10413150208 1081387936 11.661 100 78 143 2 1140999728 45481104 14130688
08:24:42 1657 8192000000 10054170960 10444898304 1086525776 11.710 100 84 163 1 1172773872 69496992 16751104
08:25:45 1192 8192000000 10071792336 10465079296 1089584240 11.739 100 98 184 4 1196511440 87449200 19478000
08:26:47 1427 8192000000 10025370928 10420891648 1091222096 11.754 100 74 145 3 1146271488 40500544 14548848
08:27:49 1269 8192000000 10061818000 10457853952 1098102480 11.820 100 86 157 1 1190369904 76019248 16248176
08:28:51 2437 8192000000 10039681472 10436370432 1091495856 11.757 100 63 124 0 1168079776 63729360 12854560
08:29:54 4766 8192000000 10042893136 10432638976 1085925056 11.704 100 70 140 0 1165833856 64767376 15141424
08:30:59 7021 8192000000 10011267504 10401030144 1060130880 11.458 99 64 121 1 1121343584 49618816 11593888
08:32:07 1342 8192000000 10022470912 10409697280 1071389824 11.565 100 80 154 1 1144168848 57037888 15741136
08:33:10 1267 8192000000 9979115232 10349031424 1020031792 11.072 99 90 168 0 1098936528 61448256 17456480
08:34:11 838 8192000000 9987488640 10361860096 1032568960 11.193 100 90 168 2 1111332128 61299792 17463376
08:35:14 1871 8192000000 9971759536 10354053120 1033008912 11.197 100 62 122 1 1095961648 51127088 11825648
08:36:17 901 8192000000 9963538176 10342711296 1026588560 11.136 99 73 143 1 1090497152 49119936 14788656
08:37:20 2636 8192000000 9984400992 10370519040 1046982288 11.332 100 59 114 0 1106990064 48349568 11658208
08:38:31 3674 8192000000 9998599008 10373074944 1058637200 11.443 99 87 157 5 1114330144 40523216 15169728
08:39:41 7901 8192000000 10046815904 10438103040 1090041936 11.743 100 66 125 0 1170897152 68023840 12831376
08:40:54 2780 8192000000 10106675600 10501197824 1106385360 11.898 100 73 142 0 1226622096 106195424 14041312
08:42:01 7779 8192000000 10092454320 10489569280 1107207440 11.906 100 59 113 0 1211538848 92331680 11999728
08:43:16 3912 8192000000 10127984400 10520428544 1123659264 12.062 100 81 142 1 1245717808 107088624 14969920
08:44:27 1193 8192000000 10172017792 10562265088 1137283824 12.190 100 92 182 1 1281959344 125793040 18882480
08:45:31 1508 8192000000 10226430816 10625433600 1147841952 12.289 100 88 172 0 1344973568 177157152 19974464
08:46:36 1599 8192000000 10222306208 10624491520 1155811984 12.364 100 91 174 0 1331129152 156621456 18695712
08:47:44 3877 8192000000 10243182592 10654175232 1163729648 12.438 100 66 132 0 1355177024 176979088 14468288
08:48:51 1392 8192000000 10263392480 10674438144 1168090304 12.479 100 80 153 2 1373851648 189790992 15970352
08:49:55 2203 8192000000 10319129904 10730352640 1179611568 12.587 100 93 178 1 1427544128 227467952 20464608
08:51:01 1036 8192000000 10338008000 10760769536 1195448160 12.734 100 96 183 0 1446177232 230177136 20551936
08:52:04 2371 8192000000 10360353440 10775703552 1201648400 12.792 100 87 165 3 1462065120 240989744 19426976
08:53:10 911 8192000000 10363036416 10770935808 1203761376 12.811 100 90 175 0 1468075328 244413888 19900064
08:54:15 970 8192000000 10419642592 10846466048 1215492208 12.920 100 95 184 0 1529126080 290434400 23199472
08:55:19 1016 8192000000 10456769232 10871042048 1230762592 13.061 100 89 164 2 1567628048 317687088 19178368
08:56:22 1332 8192000000 10484395648 10893348864 1205255152 12.825 99 92 183 0 1591038080 362999168 22783760
08:57:28 1215 8192000000 10543368112 10964799488 1214544992 12.911 100 91 180 0 1647893216 412532368 20815856
08:58:33 1535 8192000000 10574144032 11005661184 1217747184 12.941 100 94 186 0 1678967520 438777648 22442688
08:59:36 998 8192000000 10555416416 11004620800 1197931632 12.757 100 78 156 0 1659914928 442784128 19199168
09:00:42 758 8192000000 10601268944 11020853248 1208137744 12.852 100 98 184 0 1702534416 472631600 21765072
09:01:44 2733 8192000000 10642285712 11072634880 1204650688 12.820 100 61 122 0 1746918752 526864752 15403312
09:02:52 4484 8192000000 10631085088 11091615744 1171802512 12.514 99 65 127 1 1730584416 542902640 15879264
09:04:01 1374 8192000000 10710576720 11182936064 1194857440 12.729 100 87 168 2 1816086208 600228368 21000400
09:05:08 4849 8192000000 10623789360 11087998976 1191527104 12.698 100 55 107 0 1735499600 529850400 14122096
09:06:15 6956 8192000000 10603851808 11075522560 1183001280 12.618 100 54 107 0 1717713056 520702432 14009344
09:07:23 24137 8192000000 10476990816 11008143360 1185175792 12.638 100 36 70 0 1596139056 401752240 9211024
09:09:03 37486 8192000000 10421083424 10965270528 1179476880 12.585 100 29 58 0 1626081984 439054688 7550416
09:11:07 26666 8192000000 10325465552 10865381376 1161026544 12.413 100 19 39 0 1479791664 313804384 4960736
09:13:38 60115 8192000000 10071857760 10529222656 1125385984 12.078 98 24 44 1 1172986976 42649104 4951888
09:15:39 15211 8192000000 10110845168 10531254272 1127778304 12.100 99 48 86 1 1200312416 63818656 8715456
09:16:54 115759 8192000000 10090989904 10559049728 1094001376 11.781 96 29 55 1 1152256176 53043792 5211008
09:19:51 32634 8192000000 10071012432 10508861440 1138052592 12.197 100 64 115 8 1177302976 28033008 11217376
09:21:25 15538 8192000000 10109241168 10566795264 1137950160 12.196 99 34 66 0 1194989968 50004160 7035648
09:22:48 52050 8192000000 10131663104 10571014144 1130716464 12.128 98 45 85 1 1212180800 72204960 9259376
09:24:42 31626 8192000000 10136129600 10637344768 1147325152 12.284 100 29 56 0 1240414544 86240352 6849040
09:26:23 32632 8192000000 10160238720 10622746624 1154110064 12.348 100 48 96 0 1272320880 108080784 10130032
09:27:56 44929 8192000000 10072574128 10536177664 1094716112 11.787 95 36 68 0 1131075872 28941888 7417872
09:29:44 79096 8192000000 10111572368 10565578752 1123543952 12.060 98 8 16 0 1178871488 53481968 1845568
09:32:11 89869 8192000000 10051683056 10534924288 1114921168 11.979 98 19 38 0 1166367072 47114608 4331296
09:34:41 66264 8192000000 10064324288 10526470144 1115423168 11.984 99 10 18 2 1158633312 41190160 2019984
09:36:50 55454 8192000000 10091060656 10551230464 1124439456 12.069 99 27 53 0 1182088544 51618912 6030176
09:38:50 81281 8192000000 10110618128 10594979840 1115301648 11.983 97 19 37 0 1192614224 73552928 3759648
09:41:15 68394 8192000000 10062171296 10537361408 1098989104 11.828 96 16 32 1 1148673760 46761264 2923392
09:43:23 85023 8192000000 10074975024 10539450368 1104244704 11.878 97 18 36 0 1177187968 69151408 3791856
09:45:50 23895 8192000000 10058221104 10489942016 1110934816 11.941 99 32 64 0 1167611136 50544944 6131376
09:47:15 19981 8192000000 10135565024 10566492160 1119006000 12.018 99 45 90 1 1216769072 88739776 9023296
09:48:36 79321 8192000000 10140129584 10602450944 1110104320 11.933 98 14 28 0 1191115616 77541472 3469824
09:51:55 43713 8192000000 10053669296 10496176128 1095019072 11.790 97 33 64 0 1138594016 36839744 6735200
09:53:40 45544 8192000000 10053647664 10508402688 1094984224 11.790 98 34 68 0 1151887632 49913408 6990000
09:55:37 56012 8192000000 10036397872 10491801600 1096889184 11.808 99 16 32 1 1133808928 33717584 3202160
09:57:33 77715 8192000000 10041065664 10487357440 1106657200 11.901 99 13 26 0 1139043904 29881600 2505104
10:01:06 25626 8192000000 10059142896 10514563072 1134175552 12.161 100 31 63 1 1169645728 29722416 5747760
10:02:46 47358 8192000000 10088263824 10534129664 1116021152 11.989 99 28 54 1 1173617008 52149088 5446768
10:04:34 28466 8192000000 10130353232 10564112384 1126197984 12.086 100 61 120 0 1219779488 80815616 12765888
10:06:04 39848 8192000000 10082598896 10519793664 1106442848 11.899 98 31 63 0 1154822208 42041136 6338224
10:07:44 44761 8192000000 10114952944 10560765952 1117280160 12.001 98 30 60 0 1199199712 75136192 6783360
10:09:29 42517 8192000000 10177578528 10608431104 1128694064 12.109 98 34 63 0 1244300912 108728624 6878224
10:11:27 35682 8192000000 10209703344 10675032064 1146015392 12.272 99 33 64 0 1300061488 145630128 8415968
10:13:04 81914 8192000000 10129999968 10610212864 1134366208 12.163 99 18 36 0 1284026416 145177744 4482464
10:15:33 65408 8192000000 10078691152 10536214528 1092731696 11.769 96 34 68 0 1128294992 28984576 6578720
10:17:45 54456 8192000000 10075950832 10569609216 1115273408 11.982 99 12 24 0 1181274960 63557344 2444208
10:19:42 37186 8192000000 10060096400 10564046848 1122479408 12.050 100 5 10 0 1171259040 47765600 1014032
10:21:20 76434 8192000000 10095840144 10557980672 1109981536 11.932 98 12 24 0 1169560720 57037392 2541792
10:23:38 48833 8192000000 10063018544 10535559168 1096381312 11.803 97 11 20 0 1149790208 51474240 1934656
10:25:27 38081 8192000000 10072312320 10524069888 1115064928 11.980 99 26 52 0 1167252688 46925488 5262272
10:27:10 29909 8192000000 10112597392 10549919744 1105478752 11.890 97 36 72 0 1183930336 71318912 7132672
10:28:50 48670 8192000000 10127537120 10585329664 1111358192 11.945 98 28 56 0 1214157936 96348160 6451584
10:30:41 25301 8192000000 10196408192 10631122944 1128809200 12.110 100 50 100 0 1270590416 130629408 11151808
10:32:10 41801 8192000000 10110676560 10583527424 1114773632 11.978 99 30 58 0 1219679856 99053824 5852400
10:33:55 27389 8192000000 10054749424 10495086592 1093397184 11.775 97 27 52 0 1137684848 38309200 5978464
10:35:44 59372 8192000000 10083110240 10568757248 1117271616 12.001 100 24 48 0 1173870592 51781776 4817200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-03-2023 10:39:51 to 10-03-2023 10:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-03-2023 10:40:08 to 10-03-2023 10:40:10.

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          221470
MON$OLDEST_ACTIVE               222988
MON$OLDEST_SNAPSHOT             222988
MON$NEXT_TRANSACTION            222988
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                       2083552
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

10.03.23 10:40:12. 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
2023-03-10 10:40:14 No severe PSQL-related problems occured
10.03.23 10:40:16. End of report.