Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.2023 13: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 15027084 kB
MemAvailable 30420104 kB
Buffers 0 kB
Cached 16645672 kB
SwapCached 3156 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-02-27 13:37:27
DTS_END                         2023-02-27 13:37:27.1630
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/54488
TRN_ID                          223985
ATT_ID                          600
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: 9073.98 21031 1088877
customer order: creation 177.81 503 21337
customer order: refuse 15.00 476 1800
order to supplier: creation 47.18 1389 5662
order to supplier: removal 3.50 176013 420
invoice (draft): creation 46.84 3570 5621
invoice (draft): removal 50.33 1128 6039
invoice accept: apply 42.64 28139 5117
invoice accept: cancel 44.90 1343 5388
customer reserve: creation 3939.29 269 472715
customer reserve: removal 4411.64 109 529397
realization accept: apply 84.80 308 10176
realization accept: cancel 77.32 178 9278
payment from customer: creation 59.86 179 7183
payment from customer: removal 5.43 136 651
payment to supplier: creation 50.71 204 6085
payment to supplier: removal 3.59 124 431
service: total inventory turnovers 4.00 4501 480
service: total monetary turnovers 8.15 410 978
service: aggregate perf. data 0.95 17383 114
service: refresh index statistics 0.04 184259 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-02-2023 13:38:20 to 27-02-2023 13:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6496
TEST_TIME 2 5868
TEST_TIME 3 5653
TEST_TIME 4 5315
TEST_TIME 5 5516
TEST_TIME 6 5367
TEST_TIME 7 5215
TEST_TIME 8 5425
TEST_TIME 9 5962
TEST_TIME 10 6052
TEST_TIME 11 6633
TEST_TIME 12 6548
TEST_TIME 13 6565
TEST_TIME 14 6791
TEST_TIME 15 6668
TEST_TIME 16 6764
TEST_TIME 17 6876
TEST_TIME 18 7436
TEST_TIME 19 7407
TEST_TIME 20 7500
TEST_TIME 21 7585
TEST_TIME 22 7513
TEST_TIME 23 7559
TEST_TIME 24 7585
TEST_TIME 25 7772
TEST_TIME 26 7798
TEST_TIME 27 7844
TEST_TIME 28 7989
TEST_TIME 29 8047
TEST_TIME 30 8194
TEST_TIME 31 8470
TEST_TIME 32 9026
TEST_TIME 33 9119
TEST_TIME 34 9394
TEST_TIME 35 9650
TEST_TIME 36 9913
TEST_TIME 37 10319
TEST_TIME 38 10212
TEST_TIME 39 10262
TEST_TIME 40 10131
TEST_TIME 41 10040
TEST_TIME 42 10068
TEST_TIME 43 9937
TEST_TIME 44 9928
TEST_TIME 45 9865
TEST_TIME 46 9744
TEST_TIME 47 9731
TEST_TIME 48 9571
TEST_TIME 49 9538
TEST_TIME 50 9525
TEST_TIME 51 9464
TEST_TIME 52 9390
TEST_TIME 53 9463
TEST_TIME 54 9380
TEST_TIME 55 9542
TEST_TIME 56 9497
TEST_TIME 57 9518
TEST_TIME 58 9493
TEST_TIME 59 9537
TEST_TIME 60 9542
TEST_TIME 61 9521
TEST_TIME 62 9489
TEST_TIME 63 9486
TEST_TIME 64 9467
TEST_TIME 65 9522
TEST_TIME 66 9573
TEST_TIME 67 9538
TEST_TIME 68 9484
TEST_TIME 69 9547
TEST_TIME 70 9623
TEST_TIME 71 9608
TEST_TIME 72 9559
TEST_TIME 73 9575
TEST_TIME 74 9554
TEST_TIME 75 9461
TEST_TIME 76 9553
TEST_TIME 77 9474
TEST_TIME 78 9459
TEST_TIME 79 9436
TEST_TIME 80 9364
TEST_TIME 81 9352
TEST_TIME 82 9314
TEST_TIME 83 9253
TEST_TIME 84 9184
TEST_TIME 85 9208
TEST_TIME 86 9261
TEST_TIME 87 9236
TEST_TIME 88 9192
TEST_TIME 89 9182
TEST_TIME 90 9194
TEST_TIME 91 9167
TEST_TIME 92 9179
TEST_TIME 93 9189
TEST_TIME 94 9210
TEST_TIME 95 9294
TEST_TIME 96 9288
TEST_TIME 97 9338
TEST_TIME 98 9378
TEST_TIME 99 9347
TEST_TIME 100 9377
TEST_TIME 101 9337
TEST_TIME 102 9285
TEST_TIME 103 9308
TEST_TIME 104 9242
TEST_TIME 105 9212
TEST_TIME 106 9186
TEST_TIME 107 9147
TEST_TIME 108 9123
TEST_TIME 109 9108
TEST_TIME 110 9093
TEST_TIME 111 9133
TEST_TIME 112 9133
TEST_TIME 113 9153
TEST_TIME 114 9149
TEST_TIME 115 9114
TEST_TIME 116 9129
TEST_TIME 117 9128
TEST_TIME 118 9144
TEST_TIME 119 9125
TEST_TIME 120 9100

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-02-2023 13:38:24 to 27-02-2023 13:38:28.

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 1524974 1524974 0 0.00 0 21784 15 0 0
doc_list_biud 1524959 1524959 0 0.00 0 65470 44 0 0
sp_add_invoice_to_stock 5244 5117 127 2.42 46 196083 28140 0 127
sp_cancel_adding_invoice 5388 5388 0 0.00 7 104059 1344 0 0
sp_cancel_client_order 1800 1800 0 0.00 6 13755 476 0 0
sp_cancel_customer_reserve 529397 529397 0 0.00 1 65473 109 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 4 3691 137 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 4 3356 124 0 0
sp_cancel_supplier_invoice 6051 6039 12 0.19 3 36982 1128 0 12
sp_cancel_supplier_order 420 420 0 0.00 30 1437950 176014 0 0
sp_cancel_write_off 9278 9278 0 0.00 2 14257 179 0 0
sp_client_order 21337 21337 0 0.00 6 41669 503 0 0
sp_customer_reserve 472715 472715 0 0.00 0 45421 269 0 0
sp_fill_shopping_cart 32607 32607 0 0.00 1 14738 89 0 0
sp_fill_shopping_cart_clo_res 472688 472688 0 0.00 0 20653 19 0 0
sp_get_clo_for_invoice 5078 5078 0 0.00 0 28332 264 0 0
sp_kill_cost_storno 15748 15748 0 0.00 0 9376 81 0 0
sp_kill_qstorno_ret_qs2qd 1074531 1074531 0 0.00 0 65452 48 0 0
sp_kill_qty_storno 567571 567571 0 0.00 0 65469 110 0 0
sp_lock_selected_doc 21138 21138 0 0.00 0 7782 9 0 0
sp_make_cost_storno 28522 28522 0 0.00 0 21782 88 0 0
sp_multiply_rows_for_pdistr 28519 28519 0 0.00 0 8590 41 0 0
sp_multiply_rows_for_qdistr 21337 21337 0 0.00 0 11493 196 0 0
sp_pay_from_customer 7183 7183 0 0.00 5 13571 180 0 0
sp_pay_to_supplier 6085 6085 0 0.00 7 21789 205 0 0
sp_payment_common 13267 13267 0 0.00 2 13562 47 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 13741 390 0 0
sp_qd_handle_on_invoice_upd_sts 10462 10462 0 0.00 1 30057 570 0 0
sp_qd_handle_on_reserve_upd_sts 10175 10175 0 0.00 0 26318 71 0 0
sp_reserve_write_off 10176 10176 0 0.00 7 26471 308 0 0
sp_supplier_invoice 5621 5621 0 0.00 9 98659 3571 0 0
sp_supplier_order 5662 5662 0 0.00 7 47173 1389 0 0
srv_aggregate_perf_data 114 114 0 0.00 2600 148885 17384 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 749 152679 29193 0 0
srv_find_qd_qs_mism 1039107 1039107 0 0.00 0 18427 12 0 0
srv_make_invnt_saldo 480 480 0 0.00 41 95928 4501 0 0
srv_make_money_saldo 978 978 0 0.00 1 19925 411 0 0
srv_recalc_idx_stat 5 5 0 0.00 42071 464088 184260 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5124 5124 0 0.00 0 19183 1053 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 9015 503 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 2217 32 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 4008 47 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 3795 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 1629 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 2535 37 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 86847 8054 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 7416 30 0 0
t$perf-norm:sp_client_order 21337 21337 0 0.00 0 19688 45 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 5189 33 0 0
t$perf-norm:sp_pay_from_customer 7183 7183 0 0.00 0 10556 24 0 0
t$perf-norm:sp_pay_to_supplier 6085 6085 0 0.00 0 8401 26 0 0
t$perf-norm:sp_reserve_write_off 10176 10176 0 0.00 0 8777 28 0 0
t$perf-norm:sp_supplier_invoice 5621 5621 0 0.00 0 8062 40 0 0
t$perf-norm:sp_supplier_order 5662 5662 0 0.00 0 3378 35 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 553 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 409 13 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 6897 47 0 0
t$perf-norm:srv_make_money_saldo 978 978 0 0.00 0 7447 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 19 184 100 0 0
v_add_invoice_to_stock 10392 10138 254 2.44 0 3598 27 0 254
v_all_customers 39042 39042 0 0.00 0 96 4 0 0
v_all_suppliers 31484 31484 0 0.00 0 136 5 0 0
v_all_wares 42672 42672 0 0.00 0 95 4 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 2614 22 0 0
v_cancel_client_order 3600 3600 0 0.00 0 1336 11 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 2445 15 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 0 2815 13 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 761 12 0 0
v_cancel_supplier_invoice 862 838 24 2.78 0 659 29 0 24
v_cancel_supplier_order 840 840 0 0.00 0 2453 15 0 0
v_cancel_write_off 2038 2038 0 0.00 0 2961 28 0 0
v_max_id_clo_ord 5661 5661 0 0.00 0 441 6 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 1342 12 0 0
v_max_id_ord_sup 5609 5609 0 0.00 0 3502 7 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 62 8 0 0
v_max_non_paid_realizn 3625 3625 0 0.00 0 647 8 0 0
v_min_id_clo_ord 5661 5661 0 0.00 0 1125 8 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 6011 18 0 0
v_min_id_ord_sup 5609 5609 0 0.00 0 5850 16 0 0
v_min_non_paid_invoice 6084 6084 0 0.00 0 4562 13 0 0
v_min_non_paid_realizn 7182 7182 0 0.00 0 13556 18 0 0
v_our_firm 10744 10744 0 0.00 0 70 5 0 0
v_reserve_write_off 20350 20350 0 0.00 0 4209 10 0 0
x_lock_depdocs_on_canc_invoice 11008 11008 0 0.00 0 15165 130 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 2934 358 0 0
x_make_qstorno_1000_1200 5662 5662 0 0.00 2 47150 1204 0 0
x_make_qstorno_1200_2000 5621 5621 0 0.00 5 98586 3325 0 0
x_make_qstorno_2100_3300 472715 472715 0 0.00 0 45420 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2023 13:38:29 to 27-02-2023 13:38:33.

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
11:07:31 962 8192000000 8948716448 9008713728 22523248 0.274 5 4 8 0 24024160 218944 1281968
11:08:32 30282 8192000000 9716050720 10016104448 800305072 8.899 100 85 170 1 828547680 7696304 20546304
11:10:06 25876 8192000000 9764402560 10071457792 835037648 9.250 100 87 174 0 868658528 12678896 20941984
11:11:43 16210 8192000000 9809407392 10130006016 871393312 9.614 100 84 168 1 916905168 26228688 19283168
11:13:12 7415 8192000000 9871053424 10206576640 925763360 10.153 100 87 172 0 987866784 43521376 18582048
11:14:23 2986 8192000000 9937416288 10278551552 960422384 10.493 100 91 179 2 1044382448 65072464 18887600
11:15:29 2992 8192000000 9963653200 10321428480 995978336 10.840 100 84 168 0 1077343664 64281376 17083952
11:16:35 1177 8192000000 10008048160 10373013504 1027542720 11.145 100 96 185 1 1120936208 74595952 18797536
11:17:38 1386 8192000000 10016736816 10389479424 1042646912 11.290 100 85 162 1 1136034272 77793952 15593408
11:18:41 1121 8192000000 10050104304 10426368000 1064121488 11.496 100 97 192 2 1172559616 88245936 20192192
11:19:43 1425 8192000000 10046696464 10432942080 1076339600 11.613 100 91 174 1 1170935232 76253008 18342624
11:20:47 1023 8192000000 10050647840 10432946176 1077493888 11.624 100 95 182 1 1170912256 75777040 17641328
11:21:49 1159 8192000000 10029390304 10420465664 1085038656 11.695 100 68 123 1 1148333872 50203328 13091888
11:22:51 813 8192000000 10035408816 10430566400 1085352592 11.698 100 91 175 3 1157398432 54386928 17658912
11:23:53 994 8192000000 10032600384 10421567488 1092025984 11.762 100 92 182 2 1155646000 44920480 18699536
11:24:54 6095 8192000000 10045702048 10433359872 1093658608 11.777 100 71 142 0 1173055216 65622640 13773968
11:26:01 2228 8192000000 10053572496 10449391616 1097522384 11.814 100 69 136 1 1178858816 68090720 13245712
11:27:05 1146 8192000000 10058713440 10443403264 1090193088 11.744 100 95 187 2 1179640848 71627872 17819888
11:28:08 1134 8192000000 10064522576 10454929408 1095684592 11.797 100 96 183 0 1186956896 72533616 18738688
11:29:10 1128 8192000000 10042869568 10431197184 1086280512 11.707 99 97 186 0 1169524560 64148864 19095184
11:30:13 1600 8192000000 10048146464 10440609792 1088404752 11.727 100 75 139 0 1173951136 72032048 13514336
11:31:17 1131 8192000000 10047554816 10430320640 1072634592 11.577 100 91 167 1 1163075680 74036304 16404784
11:32:20 1456 8192000000 10033176656 10416021504 1068538400 11.538 100 92 172 5 1157240064 70071456 18630208
11:33:22 1510 8192000000 10025555936 10409512960 1060315632 11.460 100 78 154 2 1140930384 64694304 15920448
11:34:25 1341 8192000000 9986664800 10367832064 1043077440 11.294 100 71 130 0 1109073952 52828592 13167920
11:35:27 1408 8192000000 9987927136 10361212928 1033777856 11.205 100 94 177 2 1110390064 58447952 18164256
11:36:29 1385 8192000000 10002913984 10379735040 1039879904 11.264 100 93 174 3 1125271840 66069136 19322800
11:37:33 5294 8192000000 10003259312 10379575296 1053638320 11.396 100 89 167 3 1127969760 58855264 15476176
11:38:45 9059 8192000000 10033479472 10417684480 1060741792 11.464 100 77 146 0 1147900064 72174736 14983536
11:40:02 1790 8192000000 10035136992 10421837824 1070055664 11.553 100 77 148 2 1161857488 76665104 15136720
11:41:08 935 8192000000 10036791168 10420023296 1082274240 11.669 100 81 146 2 1160589568 62934112 15381216
11:42:18 6524 8192000000 10114021088 10504560640 1099631600 11.834 100 76 144 2 1223111568 108823264 14656704
11:43:30 12015 8192000000 10119769648 10521628672 1113383696 11.964 100 69 134 1 1230146032 102088688 14673648
11:44:45 4296 8192000000 10155733680 10561101824 1122519056 12.051 100 81 157 1 1275411760 135286080 17606624
11:46:00 2675 8192000000 10194495488 10602323968 1140349680 12.219 100 89 171 0 1314320352 155074176 18896496
11:47:06 1029 8192000000 10217217312 10624057344 1152602944 12.334 100 90 176 1 1335664192 164099248 18962000
11:48:12 2682 8192000000 10207955536 10622509056 1161558256 12.418 100 83 160 0 1332688672 154046672 17083744
11:49:18 3604 8192000000 10242821648 10649776128 1169635648 12.493 100 68 132 0 1353317088 168892672 14788768
11:50:26 899 8192000000 10285269120 10690293760 1176483664 12.557 100 92 178 0 1395005456 197558144 20963648
11:51:32 1298 8192000000 10314797088 10725744640 1180751856 12.597 100 97 187 2 1420785712 218929344 21104512
11:52:37 1696 8192000000 10350014432 10756661248 1185185104 12.639 100 83 164 0 1459944832 254932544 19827184
11:53:43 1664 8192000000 10359164480 10803113984 1193293504 12.714 100 85 164 2 1469787600 256475904 20018192
11:54:49 3141 8192000000 10343999280 10796888064 1203083504 12.805 100 83 155 4 1459356128 237417616 18855008
11:55:55 751 8192000000 10379532400 10798231552 1203982256 12.813 100 99 185 1 1487179120 261703200 21493664
11:56:59 1694 8192000000 10381278176 10807005184 1189928464 12.683 100 85 168 0 1490311488 281726592 18656432
11:58:06 1400 8192000000 10463234256 10873470976 1209997600 12.869 100 97 189 0 1563471568 332558640 20915328
11:59:12 1006 8192000000 10468239856 10881855488 1196757632 12.746 100 82 160 0 1569898496 353766048 19374816
12:00:22 1381 8192000000 10496762592 10923962368 1197701472 12.755 100 89 175 1 1601578352 383139536 20737344
12:01:29 732 8192000000 10535643648 10955640832 1194373872 12.724 100 98 190 4 1640783520 422301872 24107776
12:02:33 841 8192000000 10510031168 10956509184 1186526368 12.651 100 100 186 3 1614143280 404980736 22636176
12:03:39 745 8192000000 10551934032 10983337984 1203964576 12.813 100 96 188 2 1654133424 427613072 22555776
12:04:43 920 8192000000 10568017920 10994270208 1193428432 12.715 100 96 191 0 1671787360 455014416 23344512
12:05:49 1073 8192000000 10576523536 11006562304 1188917744 12.673 100 98 192 1 1674467264 462657216 22892304
12:06:53 2498 8192000000 10560228240 11009572864 1172979120 12.525 100 69 138 0 1660256512 469727872 17549520
12:07:59 4249 8192000000 10561794880 11019816960 1193705456 12.718 100 69 136 0 1679359712 468724848 16929408
12:09:12 5462 8192000000 10505688144 11000090624 1190407680 12.687 100 55 110 0 1622345472 418074896 13862896
12:10:33 19723 8192000000 10417518080 10854584320 1186680592 12.652 100 44 86 0 1536304544 338804800 10819152
12:11:58 19015 8192000000 10342629984 10838388736 1172768352 12.523 100 32 63 0 1460392240 280081888 7542000
12:13:51 36027 8192000000 10089891552 10567540736 1127205200 12.095 97 22 44 0 1218744720 87014192 4525328
12:15:32 8970 8192000000 10090461488 10513809408 1144126912 12.254 100 68 130 0 1190181824 31779456 14275456
12:16:46 43608 8192000000 10105952688 10545967104 1139614912 12.212 99 30 55 1 1201778336 57121952 5041472
12:18:39 95166 8192000000 10093961920 10545971200 1094676368 11.787 96 20 40 0 1179350224 80566096 4107760
12:23:15 33435 8192000000 10072042816 10495090688 1086370528 11.708 96 52 93 1 1136828496 41601184 8856784
12:25:00 54864 8192000000 10102675104 10549600256 1140157376 12.217 100 26 52 0 1211336576 65282784 5896416
12:26:57 15304 8192000000 10136098528 10574827520 1133267776 12.152 99 42 84 0 1236679344 93709312 9702256
12:28:16 25068 8192000000 10178979248 10606882816 1135097072 12.169 99 55 106 0 1250428688 102462608 12869008
12:29:43 23691 8192000000 10237906304 10687426560 1134191344 12.161 98 43 84 1 1324365120 180440176 9733600
12:31:08 29495 8192000000 10206022512 10708684800 1143257760 12.246 100 14 28 0 1313907584 166738288 3911536
12:33:11 47208 8192000000 10137254704 10591625216 1149465168 12.304 100 20 38 0 1224988496 71585600 3937728
12:35:01 63390 8192000000 10094674128 10598158336 1129432688 12.116 99 8 16 0 1185553104 54133248 1987168
12:37:04 54698 8192000000 10081969824 10540064768 1124635360 12.071 98 25 48 0 1186091296 56556032 4899904
12:39:06 19170 8192000000 10073008048 10526294016 1129750928 12.119 99 45 76 0 1173333104 36327744 7254432
12:40:32 17338 8192000000 10111188432 10548862976 1115953888 11.989 98 36 71 0 1203090416 80384464 6752064
12:41:50 18868 8192000000 10078234704 10549624832 1115825008 11.988 99 39 76 1 1205709088 82199392 7684688
12:43:11 23015 8192000000 10117685760 10556854272 1124522256 12.070 99 33 63 0 1201417136 70177296 6717584
12:44:41 39895 8192000000 10126749840 10563723264 1125509696 12.079 98 31 56 1 1219826224 87641424 6675104
12:46:27 34676 8192000000 10145363456 10586644480 1140605488 12.221 100 30 60 0 1282166288 135156768 6404032
12:48:11 67983 8192000000 10161017776 10625040384 1124007664 12.065 98 17 31 1 1192268544 65087424 3173456
12:50:19 132786 8192000000 10128125968 10610216960 1118264272 12.011 97 24 44 0 1250676160 127004864 5407024
12:53:42 62630 8192000000 10085766416 10529136640 1131851056 12.139 99 38 74 1 1177532736 37812368 7869312
12:55:47 59958 8192000000 10074918976 10546597888 1119809808 12.025 98 17 33 0 1169053184 46076944 3166432
12:57:48 81085 8192000000 10102253440 10564268032 1106683616 11.901 97 24 49 0 1176575248 64534720 5356912
13:00:22 95924 8192000000 10129819760 10598199296 1129775920 12.119 99 13 26 0 1222755248 89512288 3467040
13:03:02 63865 8192000000 10067082160 10554830848 1109700272 11.930 98 17 34 0 1203949776 90441136 3808368
13:05:46 6347 8192000000 10082302432 10505719808 1121289696 12.039 100 58 115 1 1175225632 42146336 11789600
13:06:56 4815 8192000000 10081301824 10505887744 1101588272 11.853 98 56 102 1 1159682544 48241248 9853024
13:08:06 18889 8192000000 10096152608 10532859904 1119745216 12.025 100 33 64 0 1193413584 67481472 6186896
13:09:48 56168 8192000000 10149579040 10609065984 1102107856 11.858 97 32 62 0 1219577344 110848416 6621072
13:11:49 74068 8192000000 10137959472 10667667456 1116435632 11.993 98 18 36 0 1242715904 121883456 4396816
13:14:03 16716 8192000000 10083262864 10540388352 1125438080 12.078 100 47 94 0 1171200992 36376928 9385984
13:15:22 53934 8192000000 10097328560 10552393728 1136404544 12.182 100 36 72 0 1215206112 71618800 7182768
13:17:25 37279 8192000000 10072992000 10495979520 1081941104 11.666 96 37 73 0 1145973280 56593824 7438352
13:19:02 152679 8192000000 10061299648 10544545792 1093791872 11.779 97 27 54 1 1155572656 55974512 5806272
13:22:48 21038 8192000000 10105557248 10541506560 1139284192 12.209 100 40 78 0 1203951360 56641408 8025760
13:24:28 81193 8192000000 10129718048 10594369536 1125279328 12.077 98 22 43 0 1216853632 86513776 5060528
13:27:00 51178 8192000000 10116031984 10593640448 1115477440 11.984 98 17 35 1 1227517216 107922480 4117296
13:28:54 19595 8192000000 10055505648 10514128896 1085974112 11.704 96 36 63 2 1130450480 37755520 6720848
13:30:54 80674 8192000000 10056227088 10495041536 1078769824 11.636 96 22 45 0 1131555936 48015232 4770880
13:33:25 74385 8192000000 10085937968 10537492480 1099203024 11.830 98 24 45 0 1157375728 53782832 4389872
13:35:40 23524 8192000000 10075093280 10500632576 1123416320 12.059 100 51 97 0 1167355648 34201904 9737424
13:37:06 19217 8192000000 10098847600 10545664000 1114451840 11.975 99 51 102 0 1189695360 64796720 10446800

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-02-2023 13:38:35 to 27-02-2023 13:38:51.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-02-2023 13:38:52 to 27-02-2023 13:38:54.

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          224465
MON$OLDEST_ACTIVE               224466
MON$OLDEST_SNAPSHOT             224466
MON$NEXT_TRANSACTION            224466
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                       2065376
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:340: 
:341: mscs-test-161.budzdorov.ru    Mon Feb 27 13:37:52 2023
:342:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:343: 
:344: 
:345: mscs-test-161.budzdorov.ru    Mon Feb 27 13:37:52 2023
:346:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:347: 
:348: 
:349: mscs-test-161.budzdorov.ru    Mon Feb 27 13:37:52 2023
:350:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:351: 
:352: 
:353: mscs-test-161.budzdorov.ru    Mon Feb 27 13:37:52 2023
:354:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:355: 
:356: 
:357: mscs-test-161.budzdorov.ru    Mon Feb 27 13:37:52 2023
:358:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:359: 
:360: 
:361: mscs-test-161.budzdorov.ru    Mon Feb 27 13:37:52 2023
:362:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:363: 
:364: 
:365: mscs-test-161.budzdorov.ru    Mon Feb 27 13:37:52 2023
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:367: 
--- end of diff output ---

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

27.02.23 13:38:55. 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-02-27 13:38:58 No severe PSQL-related problems occured
27.02.23 13:38:59. End of report.