Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.06.2022 13: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 16071696 kB
MemAvailable 31376672 kB
Buffers 0 kB
Cached 15664716 kB
SwapCached 2444 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-03 13:37:16
DTS_END                         2022-06-03 13:37:16.7230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36820
TRN_ID                          227785
ATT_ID                          635
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: 9044.53 23716 1085344
customer order: creation 180.64 444 21677
customer order: refuse 14.91 392 1789
order to supplier: creation 47.08 1246 5649
order to supplier: removal 3.33 168132 399
invoice (draft): creation 47.28 3001 5674
invoice (draft): removal 49.34 1021 5921
invoice accept: apply 43.91 26023 5269
invoice accept: cancel 44.81 1338 5377
customer reserve: creation 3992.58 253 479110
customer reserve: removal 4320.08 101 518409
realization accept: apply 85.53 285 10264
realization accept: cancel 79.63 188 9555
payment from customer: creation 62.23 179 7467
payment from customer: removal 5.99 124 719
payment to supplier: creation 50.29 210 6035
payment to supplier: removal 3.48 122 418
service: total inventory turnovers 4.03 4013 484
service: total monetary turnovers 8.43 347 1011
service: aggregate perf. data 0.94 15845 113
service: refresh index statistics 0.03 251056 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-06-2022 13:39:47 to 03-06-2022 13:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9716
TEST_TIME 2 8626
TEST_TIME 3 7983
TEST_TIME 4 7366
TEST_TIME 5 6884
TEST_TIME 6 7033
TEST_TIME 7 7159
TEST_TIME 8 7265
TEST_TIME 9 7291
TEST_TIME 10 7460
TEST_TIME 11 7337
TEST_TIME 12 7567
TEST_TIME 13 7534
TEST_TIME 14 7351
TEST_TIME 15 7377
TEST_TIME 16 7441
TEST_TIME 17 7482
TEST_TIME 18 7577
TEST_TIME 19 7580
TEST_TIME 20 7487
TEST_TIME 21 7785
TEST_TIME 22 7882
TEST_TIME 23 8029
TEST_TIME 24 8227
TEST_TIME 25 8359
TEST_TIME 26 8461
TEST_TIME 27 8592
TEST_TIME 28 8910
TEST_TIME 29 9351
TEST_TIME 30 9802
TEST_TIME 31 10315
TEST_TIME 32 10258
TEST_TIME 33 10718
TEST_TIME 34 11069
TEST_TIME 35 10991
TEST_TIME 36 11065
TEST_TIME 37 10907
TEST_TIME 38 10768
TEST_TIME 39 10634
TEST_TIME 40 10621
TEST_TIME 41 10644
TEST_TIME 42 10583
TEST_TIME 43 10584
TEST_TIME 44 10440
TEST_TIME 45 10282
TEST_TIME 46 10149
TEST_TIME 47 10182
TEST_TIME 48 10073
TEST_TIME 49 9964
TEST_TIME 50 9940
TEST_TIME 51 9880
TEST_TIME 52 9857
TEST_TIME 53 9868
TEST_TIME 54 9872
TEST_TIME 55 9755
TEST_TIME 56 9741
TEST_TIME 57 9697
TEST_TIME 58 9688
TEST_TIME 59 9851
TEST_TIME 60 9848
TEST_TIME 61 9836
TEST_TIME 62 9778
TEST_TIME 63 9762
TEST_TIME 64 9761
TEST_TIME 65 9813
TEST_TIME 66 9824
TEST_TIME 67 9897
TEST_TIME 68 9935
TEST_TIME 69 9901
TEST_TIME 70 9875
TEST_TIME 71 9881
TEST_TIME 72 9811
TEST_TIME 73 9718
TEST_TIME 74 9635
TEST_TIME 75 9623
TEST_TIME 76 9581
TEST_TIME 77 9506
TEST_TIME 78 9550
TEST_TIME 79 9529
TEST_TIME 80 9434
TEST_TIME 81 9388
TEST_TIME 82 9340
TEST_TIME 83 9410
TEST_TIME 84 9405
TEST_TIME 85 9410
TEST_TIME 86 9407
TEST_TIME 87 9428
TEST_TIME 88 9409
TEST_TIME 89 9404
TEST_TIME 90 9400
TEST_TIME 91 9372
TEST_TIME 92 9429
TEST_TIME 93 9494
TEST_TIME 94 9544
TEST_TIME 95 9516
TEST_TIME 96 9425
TEST_TIME 97 9403
TEST_TIME 98 9420
TEST_TIME 99 9371
TEST_TIME 100 9369
TEST_TIME 101 9327
TEST_TIME 102 9318
TEST_TIME 103 9285
TEST_TIME 104 9294
TEST_TIME 105 9263
TEST_TIME 106 9297
TEST_TIME 107 9285
TEST_TIME 108 9299
TEST_TIME 109 9292
TEST_TIME 110 9347
TEST_TIME 111 9319
TEST_TIME 112 9342
TEST_TIME 113 9393
TEST_TIME 114 9321
TEST_TIME 115 9262
TEST_TIME 116 9244
TEST_TIME 117 9229
TEST_TIME 118 9184
TEST_TIME 119 9147
TEST_TIME 120 9078

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-06-2022 13:39:50 to 03-06-2022 13:39:54.

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 1527500 1527500 0 0.00 0 36533 15 0 0
doc_list_biud 1527484 1527484 0 0.00 0 36411 40 0 0
sp_add_invoice_to_stock 5330 5269 61 1.14 31 230310 26024 0 61
sp_cancel_adding_invoice 5377 5377 0 0.00 6 94559 1338 0 0
sp_cancel_client_order 1789 1789 0 0.00 6 17591 393 0 0
sp_cancel_customer_reserve 518409 518409 0 0.00 1 36415 102 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 8942 124 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 4 6285 123 0 0
sp_cancel_supplier_invoice 5925 5921 4 0.06 3 23357 1021 0 4
sp_cancel_supplier_order 399 399 0 0.00 9 1529552 168133 0 0
sp_cancel_write_off 9555 9555 0 0.00 2 35512 188 0 0
sp_client_order 21677 21677 0 0.00 5 56541 445 0 0
sp_customer_reserve 479110 479110 0 0.00 1 103270 254 0 0
sp_fill_shopping_cart 32991 32991 0 0.00 1 12311 80 0 0
sp_fill_shopping_cart_clo_res 479075 479075 0 0.00 0 19248 18 0 0
sp_get_clo_for_invoice 5221 5221 0 0.00 0 33005 310 0 0
sp_kill_cost_storno 16069 16069 0 0.00 0 12977 76 0 0
sp_kill_qstorno_ret_qs2qd 1052693 1052693 0 0.00 0 35104 44 0 0
sp_kill_qty_storno 556934 556934 0 0.00 0 36410 101 0 0
sp_lock_selected_doc 21415 21415 0 0.00 0 11618 8 0 0
sp_make_cost_storno 28987 28987 0 0.00 0 15642 80 0 0
sp_multiply_rows_for_pdistr 28986 28986 0 0.00 0 7298 37 0 0
sp_multiply_rows_for_qdistr 21677 21677 0 0.00 0 12872 163 0 0
sp_pay_from_customer 7467 7467 0 0.00 6 13767 180 0 0
sp_pay_to_supplier 6035 6035 0 0.00 6 14922 211 0 0
sp_payment_common 13502 13502 0 0.00 2 13620 52 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 17582 323 0 0
sp_qd_handle_on_invoice_upd_sts 10597 10597 0 0.00 0 20106 503 0 0
sp_qd_handle_on_reserve_upd_sts 10264 10264 0 0.00 0 19503 70 0 0
sp_reserve_write_off 10264 10264 0 0.00 8 20901 285 0 0
sp_supplier_invoice 5674 5674 0 0.00 8 88677 3002 0 0
sp_supplier_order 5649 5649 0 0.00 7 24484 1247 0 0
srv_aggregate_perf_data 113 113 0 0.00 2902 158852 15845 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 824 158409 35785 0 0
srv_find_qd_qs_mism 1034908 1034908 0 0.00 0 36533 12 0 0
srv_make_invnt_saldo 484 484 0 0.00 61 80583 4013 0 0
srv_make_money_saldo 1011 1011 0 0.00 1 10358 347 0 0
srv_recalc_idx_stat 4 4 0 0.00 65347 477990 251057 0 0
t$perf-abend:sp_add_invoice_to_stock 61 61 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5278 5278 0 0.00 0 15179 879 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 2 6410 430 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 4612 38 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 1469 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 1078 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 789 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 676 30 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 71886 6988 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 2214 19 0 0
t$perf-norm:sp_client_order 21678 21678 0 0.00 0 15409 39 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 2994 22 0 0
t$perf-norm:sp_pay_from_customer 7467 7467 0 0.00 0 3822 18 0 0
t$perf-norm:sp_pay_to_supplier 6035 6035 0 0.00 0 5670 22 0 0
t$perf-norm:sp_reserve_write_off 10264 10264 0 0.00 0 6019 24 0 0
t$perf-norm:sp_supplier_invoice 5674 5674 0 0.00 0 5818 32 0 0
t$perf-norm:sp_supplier_order 5649 5649 0 0.00 0 4714 34 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 450 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 7099 106 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 1144 18 0 0
t$perf-norm:srv_make_money_saldo 1011 1011 0 0.00 0 1022 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 115 61 0 0
v_add_invoice_to_stock 10560 10438 122 1.15 0 5220 25 0 122
v_all_customers 40044 40044 0 0.00 0 142 4 0 0
v_all_suppliers 31413 31413 0 0.00 0 218 4 0 0
v_all_wares 43354 43354 0 0.00 0 161 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 299 12 0 0
v_cancel_client_order 3578 3578 0 0.00 0 2289 10 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 5811 15 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 1795 10 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 799 11 0 0
v_cancel_supplier_invoice 812 804 8 0.98 0 2046 26 0 8
v_cancel_supplier_order 798 798 0 0.00 0 1485 13 0 0
v_cancel_write_off 2090 2090 0 0.00 0 4677 28 0 0
v_max_id_clo_ord 5647 5647 0 0.00 0 10445 7 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 1669 15 0 0
v_max_id_ord_sup 5666 5666 0 0.00 0 10232 11 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 1037 8 0 0
v_max_non_paid_realizn 3702 3702 0 0.00 0 6149 9 0 0
v_min_id_clo_ord 5647 5647 0 0.00 0 3229 9 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 2178 9 0 0
v_min_id_ord_sup 5666 5666 0 0.00 0 7907 15 0 0
v_min_non_paid_invoice 6035 6035 0 0.00 0 13609 22 0 0
v_min_non_paid_realizn 7467 7467 0 0.00 0 6310 19 0 0
v_our_firm 10840 10840 0 0.00 0 67 4 0 0
v_reserve_write_off 20528 20528 0 0.00 0 6739 13 0 0
x_lock_depdocs_on_canc_invoice 10896 10896 0 0.00 0 17330 124 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 11585 351 0 0
x_make_qstorno_1000_1200 5649 5649 0 0.00 2 24479 1079 0 0
x_make_qstorno_1200_2000 5674 5674 0 0.00 4 88644 2781 0 0
x_make_qstorno_2100_3300 479109 479109 0 0.00 0 103261 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-06-2022 13:39:55 to 03-06-2022 13:39:59.

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:20 13 8192000000 8803298896 8816418816 7491392 0.091 3 2 3 0 7658432 105680 61360
11:08:21 31153 8192000000 9729986320 10025021440 800612832 8.903 100 89 178 0 829887248 8153936 21120480
11:09:58 20541 8192000000 9757509936 10061467648 833763280 9.237 100 87 172 1 867597248 13336768 20497200
11:11:28 12538 8192000000 9803816048 10124017664 867942512 9.580 100 80 160 1 913255392 26779456 18533424
11:12:45 9876 8192000000 9882413872 10216701952 924250016 10.138 100 88 171 2 991572768 48877088 18445664
11:13:58 4248 8192000000 9922932160 10265239552 955849920 10.448 100 82 157 0 1039537856 67868112 15819824
11:15:07 1621 8192000000 9972208288 10325803008 989102016 10.773 100 91 180 1 1089734752 82228064 18404672
11:16:10 1580 8192000000 10009565904 10372694016 1023297024 11.104 100 90 178 0 1125562160 85298304 16966832
11:17:13 1273 8192000000 10012936160 10381881344 1044207808 11.305 100 92 178 0 1131763312 69807840 17747664
11:18:15 1018 8192000000 10040316864 10420350976 1062460720 11.480 100 98 190 1 1160175888 79086160 18629008
11:19:17 1683 8192000000 10026363200 10408914944 1070138048 11.553 100 91 179 1 1155142720 67839296 17165376
11:20:32 1132 8192000000 10041643808 10422276096 1076590048 11.615 100 90 169 1 1158438720 64455728 17392944
11:21:33 806 8192000000 10064731728 10456252416 1082435792 11.671 100 81 158 2 1186776912 88163584 16177536
11:22:35 1320 8192000000 10056861744 10443841536 1087781952 11.722 100 91 176 2 1175490752 71552720 16156080
11:23:38 906 8192000000 10060001776 10450440192 1089003248 11.733 100 84 159 0 1183071552 77261360 16806944
11:24:41 1178 8192000000 10070796480 10458370048 1094101584 11.782 100 94 183 1 1192428048 79705712 18620752
11:25:43 1186 8192000000 10072091856 10456465408 1092712896 11.768 100 95 176 0 1193899264 83603712 17582656
11:26:45 1280 8192000000 10054093232 10437537792 1094955120 11.790 100 95 182 2 1170245440 57318400 17971920
11:27:48 1352 8192000000 10047535328 10434347008 1098168208 11.820 100 82 161 1 1168359136 52969376 17221552
11:28:54 1192 8192000000 10084030016 10475474944 1097513584 11.814 100 96 191 1 1207353856 90104640 19735632
11:29:56 1196 8192000000 10045763136 10433753088 1084061728 11.686 100 96 178 0 1167705808 65162704 18481376
11:30:58 1132 8192000000 10030620864 10412224512 1064134064 11.496 100 95 180 2 1160315184 77468096 18713024
11:32:00 2755 8192000000 10023755472 10410053632 1066725024 11.521 100 76 147 1 1151015968 70448400 13842544
11:33:04 2441 8192000000 9978666144 10364366848 1045063072 11.313 99 72 138 2 1111970096 52201232 14705792
11:34:07 1223 8192000000 10013735600 10386010112 1044633904 11.309 100 98 179 1 1131161104 68182832 18344368
11:35:10 1316 8192000000 9999995904 10372296704 1044854080 11.311 100 87 165 2 1121543680 59828032 16861568
11:36:11 8307 8192000000 9959595408 10336903168 1029945696 11.168 100 64 123 2 1083197984 40726064 12526224
11:37:20 13501 8192000000 10009409936 10403778560 1053702512 11.396 100 62 122 0 1149014208 83845632 11466064
11:38:43 964 8192000000 10035563168 10410688512 1072668112 11.578 100 89 164 2 1155015008 65500400 16846496
11:39:48 1411 8192000000 10032446592 10417176576 1081620128 11.663 100 79 151 0 1153826976 56307904 15898944
11:40:57 15146 8192000000 10076932320 10472771584 1092697072 11.768 100 69 137 0 1200491440 93551712 14242656
11:42:16 4699 8192000000 10075924976 10468753408 1095941104 11.799 100 68 122 3 1196516944 87752752 12823088
11:43:24 4665 8192000000 10136392144 10524999680 1116364656 11.993 100 80 152 2 1248363152 116167840 15830656
11:44:32 11933 8192000000 10140835376 10543357952 1127599920 12.099 100 60 115 0 1259735824 120218368 11917536
11:45:49 1666 8192000000 10177442112 10574761984 1139358400 12.209 100 79 157 2 1293619920 137973232 16288288
11:46:55 1845 8192000000 10193158816 10587168768 1146183024 12.274 100 87 169 0 1307624688 144137648 17304016
11:48:00 983 8192000000 10214563600 10610016256 1151531264 12.324 99 87 173 2 1328813392 157690032 19592096
11:49:03 1651 8192000000 10258068672 10659024896 1162141744 12.423 100 91 174 1 1371604464 189478304 19984416
11:50:06 3038 8192000000 10286846912 10692505600 1175190192 12.545 100 79 150 1 1399517680 206667056 17660432
11:51:13 4571 8192000000 10327222432 10736832512 1186795264 12.654 100 71 140 1 1437764064 234782528 16186272
11:52:23 1247 8192000000 10372952800 10790547456 1200017184 12.776 100 97 185 0 1479145872 258481792 20646896
11:53:28 918 8192000000 10407255440 10814345216 1197226560 12.751 100 94 185 0 1518482048 299526432 21729056
11:54:32 1213 8192000000 10463347424 10884296704 1204533792 12.818 100 97 191 0 1571270256 344725152 22011312
11:55:37 1008 8192000000 10509469680 10920792064 1208211360 12.853 100 96 187 2 1613736944 383786704 21738880
11:56:42 811 8192000000 10538925056 10955309056 1202807440 12.802 100 93 177 0 1643592256 419811536 20973280
11:57:46 1857 8192000000 10557683840 10995011584 1197289360 12.751 100 96 185 1 1662418336 442909408 22219568
11:58:54 1059 8192000000 10570890192 10997559296 1195348768 12.733 99 93 180 1 1671662848 454476176 21837904
11:59:57 2223 8192000000 10612957072 11065438208 1206067520 12.833 100 71 139 0 1718438976 495109456 17262000
12:01:06 1510 8192000000 10608766864 11053641728 1204071952 12.814 100 90 176 2 1710443248 484844512 21526784
12:02:10 2096 8192000000 10631931840 11065737216 1208769888 12.858 100 70 140 0 1734434848 507645376 18019584
12:03:16 2789 8192000000 10565291104 11032879104 1182601120 12.614 100 62 122 1 1668119664 469064240 16454304
12:04:25 7327 8192000000 10595101552 11041239040 1179923232 12.589 99 56 111 0 1695497840 501612096 13962512
12:05:48 10533 8192000000 10508465904 11004002304 1172854432 12.524 100 49 98 0 1607300416 421808912 12637072
12:07:00 13953 8192000000 10423331120 10929799168 1171732192 12.513 100 35 70 0 1542802288 361954800 9115296
12:08:15 31891 8192000000 10302131584 10779283456 1169041696 12.488 100 21 42 0 1435865328 261535936 5287696
12:10:16 32449 8192000000 10231329328 10780151808 1142365968 12.238 99 14 28 3 1309884496 164084672 3433856
12:11:57 63524 8192000000 10081327584 10536435712 1108260832 11.916 96 12 25 0 1140077232 29118256 2698144
12:14:05 54363 8192000000 10057804096 10486734848 1114945248 11.979 98 34 66 1 1135530000 13942752 6642000
12:16:03 16154 8192000000 10102733328 10525241344 1136853312 12.186 99 66 134 4 1196914672 45304432 14756928
12:17:27 158408 8192000000 10088512016 10563211264 1078544528 11.634 94 18 36 1 1139737296 57675456 3517312
12:21:09 58591 8192000000 10073677520 10500382720 1086342848 11.708 95 36 72 0 1121495552 27172928 7979776
12:23:22 64644 8192000000 10071843392 10521370624 1109536160 11.928 98 40 78 0 1150253104 32789056 7927888
12:25:29 77889 8192000000 10078954864 10517209088 1115999968 11.989 98 41 81 2 1169315808 44949104 8366736
12:27:49 12195 8192000000 10111059824 10548584448 1125058352 12.075 99 32 64 2 1201762816 69874192 6830272
12:29:04 28226 8192000000 10165072416 10599329792 1128068848 12.103 100 59 118 0 1252214080 111555728 12589504
12:30:43 35188 8192000000 10156480864 10613428224 1132674112 12.147 100 37 74 0 1275868752 134958512 8236128
12:32:19 40171 8192000000 10165067840 10609242112 1124380224 12.068 99 24 48 1 1244915424 115146480 5388720
12:34:00 45267 8192000000 10174580320 10653134848 1127542592 12.098 99 18 36 1 1265215568 133526032 4146944
12:35:47 80204 8192000000 10087129072 10614898688 1110828928 11.940 98 11 22 1 1219346512 105777632 2739952
12:38:07 69227 8192000000 10078165248 10521640960 1087486320 11.719 95 26 50 2 1139423056 46668448 5268288
12:40:17 22368 8192000000 10153978416 10585288704 1113953632 11.970 98 30 60 0 1211642144 91444976 6243536
12:42:08 57954 8192000000 10163310720 10595590144 1131293120 12.134 99 39 69 1 1267575600 128017648 8264832
12:44:16 87852 8192000000 10089579808 10558672896 1116015536 11.989 98 11 22 0 1163878560 45774288 2088736
12:47:02 6807 8192000000 10088403440 10509553664 1099762176 11.835 98 46 90 0 1168155808 59429984 8963648
12:48:19 127591 8192000000 10031332368 10486280192 1069283792 11.545 95 24 48 0 1136496560 62196112 5016656
12:51:27 53570 8192000000 10074195984 10514194432 1118552336 12.013 100 29 56 0 1184880064 60048896 6278832
12:53:23 90885 8192000000 10112660896 10567352320 1132718752 12.147 100 12 24 1 1210099824 74565968 2815104
12:55:55 91593 8192000000 10066566768 10539642880 1098953984 11.828 97 8 16 1 1138498192 37442752 2101456
12:58:27 103010 8192000000 10093096208 10583945216 1101955568 11.856 97 10 20 1 1186864992 82738976 2170448
13:01:22 21975 8192000000 10061158656 10492837888 1109687840 11.929 99 40 79 0 1160538976 43175280 7675856
13:02:50 24682 8192000000 10088075968 10540736512 1128516704 12.107 100 43 86 1 1199494192 62508064 8469424
13:04:24 32872 8192000000 10085850320 10527956992 1125750128 12.081 99 38 72 1 1183158752 50112240 7296384
13:05:59 10324 8192000000 10099309504 10526994432 1098470032 11.823 97 58 115 0 1167055728 57364624 11221072
13:07:10 45796 8192000000 10111600816 10541912064 1090389136 11.746 97 39 78 1 1187352032 89357664 7605232
13:08:56 69489 8192000000 10164594416 10616004608 1107132512 11.905 97 16 31 2 1215675168 105160144 3382512
13:11:06 103385 8192000000 10045948192 10487873536 1040579056 11.270 92 23 46 3 1077373056 31690624 5103376
13:14:01 45997 8192000000 10127548336 10570493952 1120659952 12.033 100 33 64 1 1232580704 104814800 7105952
13:15:47 84824 8192000000 10075920320 10575953920 1100411920 11.842 98 4 8 0 1186577808 85301664 864224
13:19:01 75633 8192000000 10074855568 10529730560 1098524992 11.824 97 18 36 0 1145985168 43770064 3690112
13:21:17 43467 8192000000 10094504432 10557657088 1128733600 12.109 99 18 36 0 1204103024 71296624 4072800
13:23:01 57732 8192000000 10118116288 10607730688 1112185072 11.953 98 25 50 0 1210685312 93298016 5202224
13:25:13 19688 8192000000 10090972208 10513932288 1119761888 12.025 99 51 102 0 1174751664 45138176 9851600
13:26:37 17679 8192000000 10127602112 10550931456 1119807472 12.025 99 58 115 1 1214567760 83332496 11427792
13:27:55 25725 8192000000 10135339568 10602405888 1131090144 12.132 100 37 74 1 1201256784 62838896 7327744
13:29:24 115469 8192000000 10040240928 10502422528 1098790944 11.826 97 25 50 0 1208323504 103971488 5561072
13:32:43 13935 8192000000 10062235136 10488532992 1123144224 12.057 100 48 93 2 1162808704 30131808 9532672
13:33:57 53919 8192000000 10088551408 10549342208 1119198784 12.019 99 19 38 0 1198064672 75016096 3849792
13:35:52 53251 8192000000 10086991984 10571411456 1114179120 11.972 99 5 10 0 1168709808 53409280 1121408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-06-2022 13:40:01 to 03-06-2022 13:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 61 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-06-2022 13:40:18 to 03-06-2022 13:40:20.

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          223576
MON$OLDEST_ACTIVE               227900
MON$OLDEST_SNAPSHOT             227900
MON$NEXT_TRANSACTION            227900
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                       2072800
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Fri Jun  3 13:37:35 2022
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Fri Jun  3 13:37:39 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
--- end of diff output ---

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

03.06.22 13:40:21. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-03 13:40:23 No severe PSQL-related problems occured
03.06.22 13:40:25. End of report.