Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.03.2023 07: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 14917056 kB
MemAvailable 30420744 kB
Buffers 0 kB
Cached 16800204 kB
SwapCached 3028 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-08 07:37:25
DTS_END                         2023-03-08 07:37:25.0160
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/48292
TRN_ID                          219644
ATT_ID                          608
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: 9217.01 33106 1106041
customer order: creation 167.00 498 20040
customer order: refuse 15.15 450 1818
order to supplier: creation 44.83 1420 5379
order to supplier: removal 3.30 199173 396
invoice (draft): creation 46.48 3336 5578
invoice (draft): removal 51.84 1055 6221
invoice accept: apply 42.59 25812 5111
invoice accept: cancel 47.23 1257 5668
customer reserve: creation 3937.18 245 472462
customer reserve: removal 4569.83 113 548379
realization accept: apply 82.03 287 9844
realization accept: cancel 81.41 169 9769
payment from customer: creation 58.78 186 7054
payment from customer: removal 5.33 116 639
payment to supplier: creation 48.63 209 5836
payment to supplier: removal 3.03 123 364
service: total inventory turnovers 3.96 4203 475
service: total monetary turnovers 7.46 407 895
service: aggregate perf. data 0.91 14924 109
service: refresh index statistics 0.03 408133 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-03-2023 07:39:16 to 08-03-2023 07:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9428
TEST_TIME 2 6137
TEST_TIME 3 6330
TEST_TIME 4 6065
TEST_TIME 5 6170
TEST_TIME 6 6364
TEST_TIME 7 6245
TEST_TIME 8 6094
TEST_TIME 9 6090
TEST_TIME 10 5990
TEST_TIME 11 6031
TEST_TIME 12 6184
TEST_TIME 13 6336
TEST_TIME 14 6423
TEST_TIME 15 6326
TEST_TIME 16 6795
TEST_TIME 17 6678
TEST_TIME 18 7057
TEST_TIME 19 7050
TEST_TIME 20 7091
TEST_TIME 21 7325
TEST_TIME 22 7286
TEST_TIME 23 7477
TEST_TIME 24 7788
TEST_TIME 25 7650
TEST_TIME 26 7585
TEST_TIME 27 7735
TEST_TIME 28 8032
TEST_TIME 29 8163
TEST_TIME 30 8664
TEST_TIME 31 8740
TEST_TIME 32 9018
TEST_TIME 33 9344
TEST_TIME 34 9655
TEST_TIME 35 9863
TEST_TIME 36 10133
TEST_TIME 37 10505
TEST_TIME 38 10656
TEST_TIME 39 10508
TEST_TIME 40 10534
TEST_TIME 41 10393
TEST_TIME 42 10309
TEST_TIME 43 10341
TEST_TIME 44 10281
TEST_TIME 45 10167
TEST_TIME 46 10131
TEST_TIME 47 9996
TEST_TIME 48 9862
TEST_TIME 49 9873
TEST_TIME 50 9904
TEST_TIME 51 9924
TEST_TIME 52 9850
TEST_TIME 53 9725
TEST_TIME 54 9752
TEST_TIME 55 9696
TEST_TIME 56 9709
TEST_TIME 57 9768
TEST_TIME 58 9744
TEST_TIME 59 9746
TEST_TIME 60 9620
TEST_TIME 61 9633
TEST_TIME 62 9652
TEST_TIME 63 9575
TEST_TIME 64 9545
TEST_TIME 65 9533
TEST_TIME 66 9587
TEST_TIME 67 9562
TEST_TIME 68 9570
TEST_TIME 69 9594
TEST_TIME 70 9610
TEST_TIME 71 9622
TEST_TIME 72 9591
TEST_TIME 73 9615
TEST_TIME 74 9582
TEST_TIME 75 9647
TEST_TIME 76 9656
TEST_TIME 77 9720
TEST_TIME 78 9599
TEST_TIME 79 9517
TEST_TIME 80 9544
TEST_TIME 81 9519
TEST_TIME 82 9475
TEST_TIME 83 9412
TEST_TIME 84 9371
TEST_TIME 85 9285
TEST_TIME 86 9251
TEST_TIME 87 9258
TEST_TIME 88 9211
TEST_TIME 89 9166
TEST_TIME 90 9237
TEST_TIME 91 9238
TEST_TIME 92 9232
TEST_TIME 93 9240
TEST_TIME 94 9201
TEST_TIME 95 9201
TEST_TIME 96 9223
TEST_TIME 97 9258
TEST_TIME 98 9284
TEST_TIME 99 9374
TEST_TIME 100 9441
TEST_TIME 101 9430
TEST_TIME 102 9351
TEST_TIME 103 9335
TEST_TIME 104 9320
TEST_TIME 105 9278
TEST_TIME 106 9266
TEST_TIME 107 9262
TEST_TIME 108 9232
TEST_TIME 109 9215
TEST_TIME 110 9196
TEST_TIME 111 9222
TEST_TIME 112 9189
TEST_TIME 113 9177
TEST_TIME 114 9185
TEST_TIME 115 9223
TEST_TIME 116 9181
TEST_TIME 117 9209
TEST_TIME 118 9311
TEST_TIME 119 9307
TEST_TIME 120 9239

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-03-2023 07:39:19 to 08-03-2023 07:39:23.

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 1539699 1539699 0 0.00 0 16272 14 0 0
doc_list_biud 1539684 1539684 0 0.00 0 48806 46 0 0
sp_add_invoice_to_stock 5223 5111 112 2.14 83 202387 25813 0 112
sp_cancel_adding_invoice 5668 5668 0 0.00 7 81920 1257 0 0
sp_cancel_client_order 1818 1818 0 0.00 5 11899 450 0 0
sp_cancel_customer_reserve 548379 548379 0 0.00 1 45476 114 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 3 1586 116 0 0
sp_cancel_pay_to_supplier 364 364 0 0.00 4 1286 123 0 0
sp_cancel_supplier_invoice 6235 6221 14 0.22 2 34760 1056 0 14
sp_cancel_supplier_order 396 396 0 0.00 10 1663790 199174 0 0
sp_cancel_write_off 9769 9769 0 0.00 2 12601 169 0 0
sp_client_order 20040 20040 0 0.00 6 20803 499 0 0
sp_customer_reserve 472462 472462 0 0.00 1 127724 246 0 0
sp_fill_shopping_cart 30987 30987 0 0.00 1 23304 86 0 0
sp_fill_shopping_cart_clo_res 472428 472428 0 0.00 0 23894 19 0 0
sp_get_clo_for_invoice 5067 5067 0 0.00 0 21841 284 0 0
sp_kill_cost_storno 16440 16440 0 0.00 0 6766 75 0 0
sp_kill_qstorno_ret_qs2qd 1113144 1113144 0 0.00 0 45422 49 0 0
sp_kill_qty_storno 587158 587158 0 0.00 0 48806 112 0 0
sp_lock_selected_doc 20610 20610 0 0.00 0 7171 9 0 0
sp_make_cost_storno 27800 27800 0 0.00 0 12234 83 0 0
sp_multiply_rows_for_pdistr 27796 27796 0 0.00 0 7751 40 0 0
sp_multiply_rows_for_qdistr 20040 20040 0 0.00 0 14189 205 0 0
sp_pay_from_customer 7054 7054 0 0.00 5 12960 187 0 0
sp_pay_to_supplier 5836 5836 0 0.00 7 12069 209 0 0
sp_payment_common 12888 12888 0 0.00 2 7420 47 0 0
sp_qd_handle_on_cancel_clo 1818 1818 0 0.00 0 11861 364 0 0
sp_qd_handle_on_invoice_upd_sts 10735 10735 0 0.00 0 48805 568 0 0
sp_qd_handle_on_reserve_upd_sts 9840 9840 0 0.00 0 12310 64 0 0
sp_reserve_write_off 9844 9844 0 0.00 8 12487 288 0 0
sp_supplier_invoice 5578 5578 0 0.00 10 102657 3337 0 0
sp_supplier_order 5379 5379 0 0.00 8 34496 1420 0 0
srv_aggregate_perf_data 109 109 0 0.00 2644 139958 14924 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 837 158843 30542 0 0
srv_find_qd_qs_mism 1056493 1056493 0 0.00 0 16271 10 0 0
srv_make_invnt_saldo 475 475 0 0.00 75 77489 4203 0 0
srv_make_money_saldo 895 895 0 0.00 1 12812 408 0 0
srv_recalc_idx_stat 4 4 0 0.00 161715 535888 408134 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5115 5115 0 0.00 1 14423 938 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 5474 458 0 0
t$perf-norm:sp_cancel_client_order 1818 1818 0 0.00 0 1444 25 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 1882 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 949 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 364 364 0 0.00 0 3338 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 2648 33 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 103607 7819 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 1622 18 0 0
t$perf-norm:sp_client_order 20041 20041 0 0.00 0 8726 35 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 689 14 0 0
t$perf-norm:sp_pay_from_customer 7054 7054 0 0.00 0 6406 21 0 0
t$perf-norm:sp_pay_to_supplier 5836 5836 0 0.00 0 2790 18 0 0
t$perf-norm:sp_reserve_write_off 9844 9844 0 0.00 0 4429 22 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 6572 31 0 0
t$perf-norm:sp_supplier_order 5379 5379 0 0.00 0 6308 34 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 276 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 586 16 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1124 22 0 0
t$perf-norm:srv_make_money_saldo 895 895 0 0.00 0 379 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 177 50 0 0
v_add_invoice_to_stock 10348 10124 224 2.16 0 10225 26 0 224
v_all_customers 37118 37118 0 0.00 0 250 4 0 0
v_all_suppliers 30616 30616 0 0.00 0 138 5 0 0
v_all_wares 40076 40076 0 0.00 0 147 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1624 16 0 0
v_cancel_client_order 3636 3636 0 0.00 0 6342 14 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 1232 11 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 1 3018 12 0 0
v_cancel_payment_to_supplier 728 728 0 0.00 0 385 13 0 0
v_cancel_supplier_invoice 822 794 28 3.40 0 1192 21 0 28
v_cancel_supplier_order 792 792 0 0.00 0 3963 16 0 0
v_cancel_write_off 2014 2014 0 0.00 0 2686 27 0 0
v_max_id_clo_ord 5375 5375 0 0.00 0 1448 6 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 973 12 0 0
v_max_id_ord_sup 5567 5567 0 0.00 0 7653 10 0 0
v_max_non_paid_invoice 1466 1466 0 0.00 0 578 8 0 0
v_max_non_paid_realizn 3621 3621 0 0.00 0 1422 8 0 0
v_min_id_clo_ord 5375 5375 0 0.00 0 3572 9 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 6763 24 0 0
v_min_id_ord_sup 5566 5566 0 0.00 0 8176 12 0 0
v_min_non_paid_invoice 5834 5834 0 0.00 0 4502 17 0 0
v_min_non_paid_realizn 7053 7053 0 0.00 0 5177 17 0 0
v_our_firm 9820 9820 0 0.00 0 131 5 0 0
v_reserve_write_off 19680 19680 0 0.00 0 6321 13 0 0
x_lock_depdocs_on_canc_invoice 11492 11492 0 0.00 0 11736 131 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 9816 445 0 0
x_make_qstorno_1000_1200 5379 5379 0 0.00 2 34472 1249 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 5 102567 3096 0 0
x_make_qstorno_2100_3300 472461 472461 0 0.00 0 127709 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-03-2023 07:39:24 to 08-03-2023 07:39:28.

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:28 6 8192000000 8798430816 8809533440 4473472 0.054 2 1 2 0 4572864 63616 35776
05:08:30 38047 8192000000 9721019296 10021982208 795658640 8.852 100 84 166 0 823148112 7434992 20054480
05:10:11 21242 8192000000 9762097984 10067435520 833392624 9.233 100 90 180 0 867655120 12416688 21845808
05:11:37 14188 8192000000 9788562944 10105970688 859920272 9.499 100 82 164 0 901557632 22670144 18967216
05:12:52 8953 8192000000 9853388704 10185220096 907690912 9.974 100 82 163 0 963019936 37645968 17683056
05:14:06 5452 8192000000 9907820592 10248507392 944231200 10.335 100 86 168 0 1021020784 59119072 17670512
05:15:21 2522 8192000000 9942410400 10292097024 988203168 10.764 100 88 166 1 1061844256 55369568 18271520
05:16:28 2275 8192000000 9989278528 10345631744 1013908000 11.013 100 92 175 0 1095547808 64527584 17112224
05:17:32 4613 8192000000 9995260480 10365648896 1025303520 11.123 100 76 149 0 1116914576 77383328 14227728
05:18:38 1191 8192000000 10031302016 10404356096 1052327568 11.383 100 92 181 0 1148903232 78719744 17855920
05:19:40 1061 8192000000 10048908560 10428739584 1065124896 11.506 100 97 184 0 1170632736 87740128 17767712
05:20:42 2114 8192000000 10035339216 10421813248 1066235088 11.516 100 73 142 0 1159428272 78172560 15020624
05:21:45 1156 8192000000 10040006016 10421927936 1075414480 11.604 100 90 168 0 1156724144 64041488 17268176
05:22:48 956 8192000000 10054919424 10445889536 1081341664 11.660 100 84 168 0 1179134176 79153168 18639344
05:23:51 1125 8192000000 10052465344 10437246976 1085354512 11.698 100 93 181 2 1177822960 74918272 17550176
05:24:53 1308 8192000000 10063181824 10447167488 1089348656 11.736 100 95 188 2 1180606288 71664992 19592640
05:25:55 1163 8192000000 10079592208 10465083392 1093826384 11.779 100 98 192 3 1199987664 87332112 18829168
05:26:57 1000 8192000000 10068645792 10458894336 1093623552 11.777 100 95 182 3 1186575264 73439472 19512240
05:27:59 2880 8192000000 10054175488 10452910080 1094776128 11.788 100 74 145 1 1182491168 73083600 14631440
05:29:03 1076 8192000000 10030557680 10419277824 1096797296 11.807 100 85 166 2 1154078224 39905568 17375360
05:30:06 905 8192000000 10028179472 10411397120 1068903824 11.542 100 95 174 2 1146842368 60673840 17264704
05:31:10 1039 8192000000 10070430672 10461376512 1086273232 11.707 100 98 177 2 1191581584 85783504 19524848
05:32:12 1064 8192000000 10071255184 10461556736 1085547216 11.700 100 89 169 3 1195566480 91734608 18284656
05:33:14 1121 8192000000 10008742832 10387046400 1044676176 11.310 100 86 154 1 1133211840 71981264 16554400
05:34:18 1069 8192000000 10008893376 10382385152 1035740144 11.224 100 96 181 2 1132651264 78073888 18837232
05:35:19 925 8192000000 10009323808 10382348288 1037777328 11.243 100 84 156 1 1125322576 71495536 16049712
05:36:22 931 8192000000 9981637840 10355617792 1025675744 11.127 100 87 158 1 1110604224 68046752 16881728
05:37:23 1017 8192000000 9980867520 10353819648 1037950784 11.245 100 91 172 3 1101996368 45318352 18727232
05:38:29 9673 8192000000 9992864992 10377314304 1059501936 11.452 100 69 123 1 1114123936 41333136 13288864
05:39:46 1088 8192000000 10018566224 10398138368 1068054944 11.534 100 88 158 1 1136382848 52367392 15960512
05:40:50 3718 8192000000 10072347136 10460880896 1079836384 11.646 100 78 154 2 1190246864 94123472 16287008
05:42:01 1457 8192000000 10099710400 10486525952 1094378432 11.784 100 89 168 1 1218738624 105412128 18948064
05:43:05 2229 8192000000 10116303408 10507812864 1108773216 11.921 100 92 166 3 1230648240 103547408 18327616
05:44:12 2206 8192000000 10128309280 10527064064 1111448224 11.946 100 69 126 0 1247684672 123191072 13045376
05:45:19 9865 8192000000 10141802160 10542190592 1116512352 11.994 100 61 120 1 1253812544 124018256 13281936
05:46:34 1848 8192000000 10176924832 10570510336 1128375104 12.106 100 86 156 1 1285942320 141609360 15957856
05:47:44 2713 8192000000 10234187120 10634076160 1153688352 12.344 100 76 151 1 1350985072 180437824 16858896
05:48:51 1459 8192000000 10286347920 10683617280 1164197424 12.443 100 95 180 1 1398284752 214394800 19692528
05:49:55 1518 8192000000 10320051104 10732797952 1173761712 12.532 100 86 166 0 1430629872 237254480 19613680
05:51:02 957 8192000000 10367397824 10768613376 1179222448 12.583 100 98 178 4 1480303072 280346928 20733696
05:52:07 4293 8192000000 10372034544 10797117440 1189840112 12.682 100 76 145 0 1482917424 276539504 16537808
05:53:17 3283 8192000000 10409340464 10862190592 1192449216 12.706 100 89 173 0 1524209600 310921792 20838592
05:54:27 2818 8192000000 10426599392 10863251456 1202846128 12.803 100 90 174 3 1532130528 309036464 20247936
05:55:33 1274 8192000000 10453410864 10884366336 1205998464 12.832 100 92 178 4 1559468992 333497136 19973392
05:56:38 1423 8192000000 10490167344 10905817088 1216130208 12.926 100 93 186 0 1598840368 360479904 22230256
05:57:44 940 8192000000 10502307696 10936532992 1217092064 12.935 100 99 194 2 1605939152 365762864 23084224
05:58:49 828 8192000000 10544812928 10968363008 1208480016 12.855 99 98 194 0 1650796144 419436528 22879600
05:59:53 1126 8192000000 10559944096 11004948480 1222199552 12.982 100 97 188 0 1659961520 414808304 22953664
06:00:56 1282 8192000000 10579587088 11023659008 1210221184 12.871 99 93 180 1 1681814976 449661472 21932320
06:02:01 4816 8192000000 10563647600 11012599808 1212032496 12.888 100 60 115 3 1671699648 443570368 16096784
06:03:18 2295 8192000000 10661908752 11076521984 1211910560 12.887 100 93 186 1 1764411568 529194384 23306624
06:04:25 912 8192000000 10649274688 11089809408 1196237760 12.741 99 96 184 1 1747135664 528603104 22294800
06:05:28 9144 8192000000 10613257536 11069349888 1194029408 12.721 100 52 104 0 1719804384 512171216 13603760
06:06:42 15252 8192000000 10557786256 11014991872 1148068320 12.291 97 36 72 0 1652330944 494583440 9679184
06:08:06 10315 8192000000 10463066112 10920144896 1146939600 12.281 98 34 68 0 1554967184 398797456 9230128
06:09:23 5626 8192000000 10472365536 10933411840 1159031824 12.394 100 57 114 0 1565245808 391963536 14250448
06:10:30 25212 8192000000 10394761696 10896650240 1166848704 12.467 100 24 45 0 1507584352 334992048 5743600
06:11:59 16013 8192000000 10327354176 10805997568 1158112384 12.386 100 34 67 0 1451364944 284937120 8315440
06:13:18 11887 8192000000 10222486352 10699878400 1140403072 12.219 100 34 67 0 1344711968 196107248 8201648
06:14:36 62278 8192000000 10045181168 10544689152 1077116048 11.620 96 10 20 1 1166264672 86941360 2207264
06:16:45 34953 8192000000 10110980352 10547703808 1136169184 12.179 100 43 82 0 1215752096 71301504 8281408
06:18:25 20961 8192000000 10115437104 10536878080 1136544560 12.183 100 59 112 2 1208732960 61267168 10921232
06:20:02 122272 8192000000 10053736288 10503081984 1132670896 12.147 100 25 48 1 1206859168 68899664 5288608
06:23:08 158842 8192000000 10101319920 10541559808 1090431648 11.747 95 26 50 1 1125457520 29676352 5349520
06:26:51 19823 8192000000 10144607568 10580176896 1128181984 12.104 99 48 89 0 1245752688 108040672 9530032
06:28:13 16321 8192000000 10148315216 10573185024 1132373952 12.144 99 59 114 1 1237109552 91142400 13593200
06:29:35 52267 8192000000 10186392080 10635661312 1150043184 12.310 99 32 64 1 1281944176 124803392 7097600
06:31:33 47022 8192000000 10201183536 10663612416 1149467136 12.304 100 29 57 0 1311835024 156416400 5951488
06:33:24 27116 8192000000 10165341344 10644377600 1134955760 12.168 99 15 29 1 1290999152 152676192 3367200
06:35:00 47514 8192000000 10103749552 10594357248 1143830544 12.252 100 16 28 1 1221568304 74323664 3414096
06:36:53 71588 8192000000 10098669792 10563600384 1129467408 12.116 99 29 55 0 1211950752 76609696 5873648
06:39:33 58532 8192000000 10078437760 10576330752 1124668960 12.071 99 14 28 0 1177602032 50254864 2678208
06:41:43 51146 8192000000 10070883776 10515390464 1115906096 11.988 98 15 30 0 1161504032 42515520 3082416
06:43:36 43938 8192000000 10072806768 10518548480 1091316480 11.755 96 17 34 0 1159707760 65360096 3031184
06:45:20 14297 8192000000 10070729600 10500857856 1116016544 11.989 99 51 98 1 1153840448 27737536 10086368
06:46:36 51827 8192000000 10102051648 10565013504 1120949920 12.036 99 28 56 0 1195116320 67687776 6478624
06:48:34 50701 8192000000 10104314368 10554290176 1135483136 12.173 100 32 64 0 1200542560 58172560 6886864
06:50:26 52284 8192000000 10086476624 10524532736 1079091440 11.639 95 18 36 0 1141224272 58447920 3684912
06:52:18 82086 8192000000 10127295728 10591514624 1120771408 12.034 99 21 42 1 1206218912 80681248 4766256
06:54:41 103106 8192000000 10116386016 10541973504 1120341408 12.030 99 32 61 0 1202835728 74175664 8318656
06:57:27 32919 8192000000 10077869504 10556022784 1128036688 12.103 100 22 44 0 1206009424 73585184 4387552
06:59:01 60712 8192000000 10055955312 10514788352 1125780208 12.082 99 14 28 0 1157164976 28450016 2934752
07:01:02 55188 8192000000 10071571696 10539479040 1107760416 11.911 97 8 16 0 1171876704 62237696 1878592
07:03:43 47699 8192000000 10080977920 10548998144 1115091712 11.981 98 22 44 0 1178520384 58352016 5076656
07:05:35 63126 8192000000 10121510896 10573643776 1129555936 12.117 99 26 50 0 1197511552 62579888 5375728
07:08:06 46612 8192000000 10080028368 10519011328 1123018240 12.055 98 20 40 0 1173986560 46643776 4324544
07:09:53 30439 8192000000 10079078656 10549616640 1097282672 11.812 97 31 62 0 1181822464 77760416 6779376
07:11:40 9018 8192000000 10144046752 10568896512 1121882512 12.045 100 55 108 1 1233711216 100260112 11568592
07:12:50 16281 8192000000 10176494688 10598817792 1133031456 12.150 100 52 100 1 1274620176 131288560 10300160
07:14:07 17434 8192000000 10194722032 10612322304 1142474704 12.239 100 50 94 0 1288233632 135163072 10595856
07:15:26 47528 8192000000 10204631024 10666536960 1155142320 12.358 100 52 103 0 1306661792 140601344 10918128
07:17:40 76657 8192000000 10101431072 10585739264 1146255792 12.274 100 13 26 0 1200833664 52438208 2139664
07:19:58 31215 8192000000 10064711744 10525417472 1136793776 12.185 99 62 111 5 1166950192 17198144 12958272
07:21:33 67621 8192000000 10075282192 10541649920 1127351840 12.096 98 11 20 0 1157530032 28107072 2071120
07:23:41 63013 8192000000 10064145504 10513346560 1098545808 11.824 96 10 20 0 1127986544 27552224 1888512
07:25:49 30049 8192000000 10102600880 10550226944 1142718304 12.241 100 29 58 1 1197424496 48681392 6024800
07:27:21 51126 8192000000 10138722432 10574663680 1107736320 11.911 96 31 62 0 1182052688 67122864 7193504
07:29:13 50662 8192000000 10140428528 10575130624 1097509872 11.814 96 27 51 0 1187966832 84457680 5999280
07:31:17 49721 8192000000 10128384384 10575233024 1096671808 11.806 97 32 62 0 1209323536 105706528 6945200
07:33:07 13123 8192000000 10134763088 10559025152 1128559008 12.108 100 53 103 0 1230975248 91708144 10708096
07:34:24 10345 8192000000 10151712736 10583154688 1126762656 12.091 100 61 118 0 1239660768 100617984 12280128
07:35:37 72464 8192000000 10041732752 10462445568 1062500112 11.480 95 14 25 1 1118024000 52921248 2602640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 08-03-2023 07:39:30 to 08-03-2023 07:39:47.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-03-2023 07:39:48 to 08-03-2023 07:39:50.

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          218633
MON$OLDEST_ACTIVE               220109
MON$OLDEST_SNAPSHOT             220109
MON$NEXT_TRANSACTION            220109
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                       2075168
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

08.03.23 07:39:51. 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-08 07:39:53 No severe PSQL-related problems occured
08.03.23 07:39:55. End of report.