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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16251540 kB
MemAvailable 31462456 kB
Buffers 0 kB
Cached 15424768 kB
SwapCached 2688 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-04-18 10:37:17
DTS_END                         2022-04-18 10:37:17.8500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(526:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40464
TRN_ID                          207258
ATT_ID                          566
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: 8374.95 24768 1004994
customer order: creation 158.74 524 19049
customer order: refuse 13.63 505 1635
order to supplier: creation 40.86 1491 4903
order to supplier: removal 3.13 196973 376
invoice (draft): creation 42.97 3829 5156
invoice (draft): removal 48.52 1152 5822
invoice accept: apply 37.05 28371 4446
invoice accept: cancel 43.20 1427 5184
customer reserve: creation 3477.83 264 417340
customer reserve: removal 4241.57 112 508988
realization accept: apply 76.23 301 9148
realization accept: cancel 74.18 190 8901
payment from customer: creation 54.09 206 6491
payment from customer: removal 4.96 128 595
payment to supplier: creation 43.62 231 5234
payment to supplier: removal 3.06 154 367
service: total inventory turnovers 3.48 4760 417
service: total monetary turnovers 6.97 436 836
service: aggregate perf. data 0.84 19033 101
service: refresh index statistics 0.04 235265 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-04-2022 10:38:32 to 18-04-2022 10:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12832
TEST_TIME 2 7904
TEST_TIME 3 7365
TEST_TIME 4 6891
TEST_TIME 5 6646
TEST_TIME 6 6985
TEST_TIME 7 6896
TEST_TIME 8 6917
TEST_TIME 9 6772
TEST_TIME 10 6779
TEST_TIME 11 6987
TEST_TIME 12 6911
TEST_TIME 13 7052
TEST_TIME 14 7017
TEST_TIME 15 7040
TEST_TIME 16 7019
TEST_TIME 17 6959
TEST_TIME 18 6937
TEST_TIME 19 6904
TEST_TIME 20 6964
TEST_TIME 21 7217
TEST_TIME 22 7466
TEST_TIME 23 7488
TEST_TIME 24 7622
TEST_TIME 25 7627
TEST_TIME 26 7769
TEST_TIME 27 8326
TEST_TIME 28 8385
TEST_TIME 29 8529
TEST_TIME 30 8548
TEST_TIME 31 8746
TEST_TIME 32 9023
TEST_TIME 33 9008
TEST_TIME 34 9318
TEST_TIME 35 9776
TEST_TIME 36 9732
TEST_TIME 37 9932
TEST_TIME 38 10384
TEST_TIME 39 10271
TEST_TIME 40 10159
TEST_TIME 41 9922
TEST_TIME 42 9952
TEST_TIME 43 10024
TEST_TIME 44 9924
TEST_TIME 45 9795
TEST_TIME 46 9849
TEST_TIME 47 9725
TEST_TIME 48 9612
TEST_TIME 49 9494
TEST_TIME 50 9460
TEST_TIME 51 9364
TEST_TIME 52 9311
TEST_TIME 53 9251
TEST_TIME 54 9125
TEST_TIME 55 9071
TEST_TIME 56 9032
TEST_TIME 57 8927
TEST_TIME 58 8902
TEST_TIME 59 8867
TEST_TIME 60 8873
TEST_TIME 61 8790
TEST_TIME 62 8724
TEST_TIME 63 8649
TEST_TIME 64 8622
TEST_TIME 65 8686
TEST_TIME 66 8760
TEST_TIME 67 8753
TEST_TIME 68 8736
TEST_TIME 69 8746
TEST_TIME 70 8811
TEST_TIME 71 8844
TEST_TIME 72 8853
TEST_TIME 73 8856
TEST_TIME 74 8846
TEST_TIME 75 8873
TEST_TIME 76 8898
TEST_TIME 77 8999
TEST_TIME 78 8957
TEST_TIME 79 8961
TEST_TIME 80 8878
TEST_TIME 81 8796
TEST_TIME 82 8757
TEST_TIME 83 8799
TEST_TIME 84 8731
TEST_TIME 85 8707
TEST_TIME 86 8675
TEST_TIME 87 8609
TEST_TIME 88 8643
TEST_TIME 89 8590
TEST_TIME 90 8557
TEST_TIME 91 8509
TEST_TIME 92 8512
TEST_TIME 93 8503
TEST_TIME 94 8495
TEST_TIME 95 8571
TEST_TIME 96 8575
TEST_TIME 97 8626
TEST_TIME 98 8595
TEST_TIME 99 8606
TEST_TIME 100 8599
TEST_TIME 101 8649
TEST_TIME 102 8670
TEST_TIME 103 8665
TEST_TIME 104 8683
TEST_TIME 105 8681
TEST_TIME 106 8644
TEST_TIME 107 8583
TEST_TIME 108 8537
TEST_TIME 109 8517
TEST_TIME 110 8536
TEST_TIME 111 8499
TEST_TIME 112 8461
TEST_TIME 113 8428
TEST_TIME 114 8443
TEST_TIME 115 8430
TEST_TIME 116 8432
TEST_TIME 117 8443
TEST_TIME 118 8459
TEST_TIME 119 8446
TEST_TIME 120 8396

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-04-2022 10:38:36 to 18-04-2022 10:38:39.

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 1391000 1391000 0 0.00 0 28230 14 0 0
doc_list_biud 1390988 1390988 0 0.00 0 32016 48 0 0
sp_add_invoice_to_stock 4569 4446 123 2.69 45 175203 28371 0 123
sp_cancel_adding_invoice 5184 5184 0 0.00 8 141776 1427 0 0
sp_cancel_client_order 1635 1635 0 0.00 6 11036 506 0 0
sp_cancel_customer_reserve 508988 508988 0 0.00 1 32025 113 0 0
sp_cancel_pay_from_customer 595 595 0 0.00 4 2812 128 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 4 7549 154 0 0
sp_cancel_supplier_invoice 5832 5822 10 0.17 3 23297 1152 0 10
sp_cancel_supplier_order 376 376 0 0.00 8 1439183 196974 0 0
sp_cancel_write_off 8901 8901 0 0.00 2 21311 190 0 0
sp_client_order 19049 19049 0 0.00 5 26671 524 0 0
sp_customer_reserve 417340 417340 0 0.00 1 94579 265 0 0
sp_fill_shopping_cart 29088 29088 0 0.00 1 14931 89 0 0
sp_fill_shopping_cart_clo_res 417308 417308 0 0.00 0 24329 21 0 0
sp_get_clo_for_invoice 4405 4405 0 0.00 0 32518 371 0 0
sp_kill_cost_storno 15047 15047 0 0.00 0 28217 94 0 0
sp_kill_qstorno_ret_qs2qd 1033075 1033075 0 0.00 0 31974 49 0 0
sp_kill_qty_storno 544455 544455 0 0.00 0 32014 113 0 0
sp_lock_selected_doc 18735 18735 0 0.00 0 7338 7 0 0
sp_make_cost_storno 25278 25278 0 0.00 0 18443 87 0 0
sp_multiply_rows_for_pdistr 25273 25273 0 0.00 0 7903 44 0 0
sp_multiply_rows_for_qdistr 19048 19048 0 0.00 0 16994 192 0 0
sp_pay_from_customer 6491 6491 0 0.00 5 26275 207 0 0
sp_pay_to_supplier 5234 5234 0 0.00 6 26206 231 0 0
sp_payment_common 11724 11724 0 0.00 2 8152 49 0 0
sp_qd_handle_on_cancel_clo 1635 1635 0 0.00 0 11024 427 0 0
sp_qd_handle_on_invoice_upd_sts 9588 9588 0 0.00 0 29405 604 0 0
sp_qd_handle_on_reserve_upd_sts 9145 9145 0 0.00 0 13734 70 0 0
sp_reserve_write_off 9148 9148 0 0.00 9 13755 302 0 0
sp_supplier_invoice 5156 5156 0 0.00 9 93192 3829 0 0
sp_supplier_order 4903 4903 0 0.00 9 28978 1491 0 0
srv_aggregate_perf_data 101 101 0 0.00 2882 153977 19034 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 788 157234 35728 0 0
srv_find_qd_qs_mism 961394 961394 0 0.00 0 10235 10 0 0
srv_make_invnt_saldo 417 417 0 0.00 73 70600 4760 0 0
srv_make_money_saldo 836 836 0 0.00 2 13552 437 0 0
srv_recalc_idx_stat 5 5 0 0.00 39515 431037 235266 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4449 4449 0 0.00 1 18071 1180 0 0
t$perf-norm:sp_cancel_adding_invoice 359 359 0 0.00 2 8215 613 0 0
t$perf-norm:sp_cancel_client_order 1635 1635 0 0.00 0 4997 40 0 0
t$perf-norm:sp_cancel_customer_reserve 582 582 0 0.00 0 1011 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 595 595 0 0.00 0 655 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 7433 44 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 2166 57 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 89049 8849 0 0
t$perf-norm:sp_cancel_write_off 907 907 0 0.00 0 9078 40 0 0
t$perf-norm:sp_client_order 19049 19049 0 0.00 0 9711 44 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 5137 26 0 0
t$perf-norm:sp_pay_from_customer 6491 6491 0 0.00 0 11654 25 0 0
t$perf-norm:sp_pay_to_supplier 5234 5234 0 0.00 0 8551 27 0 0
t$perf-norm:sp_reserve_write_off 9148 9148 0 0.00 0 8675 27 0 0
t$perf-norm:sp_supplier_invoice 5156 5156 0 0.00 0 6452 42 0 0
t$perf-norm:sp_supplier_order 4903 4903 0 0.00 0 4360 38 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 746 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 704 20 0 0
t$perf-norm:srv_make_invnt_saldo 417 417 0 0.00 0 4950 32 0 0
t$perf-norm:srv_make_money_saldo 836 836 0 0.00 0 7071 19 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 421 143 0 0
v_add_invoice_to_stock 9046 8800 246 2.71 0 6306 29 0 246
v_all_customers 34638 34638 0 0.00 0 66 4 0 0
v_all_suppliers 27822 27822 0 0.00 0 275 4 0 0
v_all_wares 38090 38090 0 0.00 0 80 4 0 0
v_cancel_adding_invoice 718 718 0 0.00 0 396 15 0 0
v_cancel_client_order 3270 3270 0 0.00 0 5326 11 0 0
v_cancel_customer_prepayment 1190 1190 0 0.00 0 2733 15 0 0
v_cancel_customer_reserve 1164 1164 0 0.00 1 2678 12 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 338 10 0 0
v_cancel_supplier_invoice 758 738 20 2.63 0 4085 32 0 20
v_cancel_supplier_order 752 752 0 0.00 0 3353 15 0 0
v_cancel_write_off 1814 1814 0 0.00 0 3444 31 0 0
v_max_id_clo_ord 4899 4899 0 0.00 0 527 5 0 0
v_max_id_clo_res 917 917 0 0.00 0 1888 12 0 0
v_max_id_ord_sup 5142 5142 0 0.00 0 5335 8 0 0
v_max_non_paid_invoice 1363 1363 0 0.00 0 3009 11 0 0
v_max_non_paid_realizn 3398 3398 0 0.00 0 1811 9 0 0
v_min_id_clo_ord 4898 4898 0 0.00 0 4924 11 0 0
v_min_id_clo_res 917 917 0 0.00 0 6994 20 0 0
v_min_id_ord_sup 5142 5142 0 0.00 0 4881 9 0 0
v_min_non_paid_invoice 5234 5234 0 0.00 0 6666 16 0 0
v_min_non_paid_realizn 6490 6490 0 0.00 0 7652 18 0 0
v_our_firm 9636 9636 0 0.00 0 70 5 0 0
v_reserve_write_off 18290 18290 0 0.00 0 6075 12 0 0
x_lock_depdocs_on_canc_invoice 10637 10637 0 0.00 0 17361 158 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 9436 431 0 0
x_make_qstorno_1000_1200 4903 4903 0 0.00 2 28962 1309 0 0
x_make_qstorno_1200_2000 5156 5156 0 0.00 3 93066 3582 0 0
x_make_qstorno_2100_3300 417336 417336 0 0.00 0 94578 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-04-2022 10:38:40 to 18-04-2022 10:38:44.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:21 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 28155 8192000000 9720797616 10021826560 795359840 8.849 100 86 172 0 823923040 7122592 21440608
08:09:59 31419 8192000000 9771833872 10084474880 846289216 9.363 100 88 176 0 879011856 11996880 20725760
08:11:32 26513 8192000000 9810725104 10130223104 873372496 9.634 100 86 172 2 914126256 21108480 19645280
08:13:05 8109 8192000000 9860781776 10193731584 924069552 10.136 100 86 170 0 978997472 36469984 18457936
08:14:16 5616 8192000000 9912331840 10255085568 949675760 10.388 100 85 165 1 1026300416 59712992 16911664
08:15:24 2033 8192000000 9979268832 10332803072 985687184 10.740 100 90 171 1 1101044288 97156736 18200368
08:16:29 1551 8192000000 9986880944 10344431616 1008492528 10.961 100 96 184 0 1106107312 79599056 18015728
08:17:32 1136 8192000000 10018634944 10393300992 1030899920 11.177 100 99 190 5 1137349440 86801696 19647824
08:18:35 1037 8192000000 10041139872 10413572096 1056105488 11.419 100 93 186 0 1158582752 83705424 18771840
08:19:38 1334 8192000000 10050694912 10429108224 1068007072 11.533 100 95 176 0 1169740848 83869344 17864432
08:20:41 1195 8192000000 10048671440 10426535936 1076575312 11.615 100 93 181 0 1171746080 76489760 18681008
08:21:44 1717 8192000000 10053485168 10444357632 1075441376 11.604 100 76 150 1 1175718928 84758928 15518624
08:22:47 816 8192000000 10034727200 10416214016 1081494192 11.662 100 98 187 1 1157178416 55363232 20320992
08:23:48 1092 8192000000 10038957056 10432086016 1091082000 11.753 100 83 155 1 1160141888 52831088 16228800
08:24:51 960 8192000000 10071601312 10457706496 1091674784 11.759 100 95 180 1 1191252848 80937936 18640128
08:25:54 1304 8192000000 10075647040 10466611200 1089234288 11.735 100 96 188 0 1199134096 90391296 19508512
08:26:56 1275 8192000000 10068064160 10458861568 1095218160 11.792 100 98 195 0 1186883088 72545808 19119120
08:27:58 2322 8192000000 10050523584 10451464192 1094613968 11.787 100 73 136 1 1177243344 68496080 14133296
08:29:03 1355 8192000000 10068637024 10458808320 1093784352 11.779 100 93 180 1 1187714752 74269168 19661232
08:30:05 1609 8192000000 10049372448 10437455872 1084660480 11.692 100 85 163 0 1168745936 67351440 16734016
08:31:09 1372 8192000000 10039884800 10422910976 1062656000 11.482 100 91 175 0 1158388080 77788688 17943392
08:32:12 1245 8192000000 10003773872 10382979072 1052905456 11.389 99 90 167 2 1126834512 57253712 16675344
08:33:16 1194 8192000000 10000668896 10372952064 1038509376 11.250 99 85 154 2 1114285728 60699168 15077184
08:34:18 4031 8192000000 9991707536 10372034560 1044505232 11.308 100 74 134 3 1111320080 53267344 13547504
08:35:27 4946 8192000000 9957356720 10332557312 1012730432 11.002 99 66 126 0 1072638304 46649360 13258512
08:36:33 1145 8192000000 9978349552 10346782720 1020231472 11.074 100 94 179 2 1098888016 59868992 18787552
08:37:37 7483 8192000000 10008767264 10388336640 1057262896 11.430 100 72 137 2 1132848464 62496096 13089472
08:38:51 8696 8192000000 10025319152 10412978176 1074710768 11.597 100 76 144 2 1158577760 69246448 14620544
08:40:04 1593 8192000000 10039157328 10426830848 1081815600 11.665 100 81 149 2 1159017488 62450192 14751696
08:41:10 789 8192000000 10069734288 10449371136 1091021456 11.752 100 92 170 4 1184589936 75757104 17811376
08:42:17 3889 8192000000 10084246880 10480050176 1101157424 11.849 100 75 142 1 1209164768 92551328 15456016
08:43:26 1133 8192000000 10107990544 10496602112 1104250704 11.878 100 91 170 3 1226261888 103993312 18017872
08:44:33 6317 8192000000 10111468208 10514829312 1114224624 11.972 100 71 142 0 1225751360 96149440 15377296
08:45:45 2364 8192000000 10143576208 10539528192 1127758336 12.100 100 80 148 1 1257241168 111700048 17782784
08:46:53 2886 8192000000 10176858800 10572013568 1140160352 12.217 100 84 160 1 1291625952 136108016 15357584
08:48:03 1486 8192000000 10199506512 10608930816 1146498800 12.277 100 91 182 2 1317897536 149774624 21624112
08:49:08 7920 8192000000 10236547024 10641563648 1155705072 12.363 100 75 149 1 1346789616 174346464 16738080
08:50:20 1892 8192000000 10244562336 10657751040 1159374176 12.397 100 83 161 2 1359496928 181452960 18669792
08:51:30 2381 8192000000 10288655200 10689634304 1176678032 12.559 100 82 163 0 1402905760 207440656 18787072
08:52:36 1040 8192000000 10331263808 10739060736 1183559648 12.623 100 93 184 3 1442805536 237180880 22065008
08:53:42 1400 8192000000 10363606224 10772267008 1181867856 12.608 100 85 165 0 1469965456 269196976 18900624
08:54:50 1126 8192000000 10376913264 10787504128 1183322896 12.621 100 84 164 0 1492958080 290775792 18859392
08:55:59 835 8192000000 10416433680 10831081472 1187384720 12.659 100 89 174 0 1522277056 314372464 20519872
08:57:08 782 8192000000 10491434672 10899513344 1197381328 12.752 100 99 192 0 1601678704 380868672 23428704
08:58:13 1326 8192000000 10473012368 10923913216 1197171776 12.750 100 93 178 1 1573979376 355683280 21124320
08:59:17 1215 8192000000 10475478128 10906923008 1193140400 12.713 100 97 192 0 1582743040 366404672 23197968
09:00:22 1295 8192000000 10538750784 10954485760 1193294832 12.714 100 99 194 0 1641742096 425381424 23065840
09:01:26 784 8192000000 10523184064 10964508672 1172393344 12.519 99 96 187 1 1626578432 431092448 23092640
09:02:32 1008 8192000000 10576914304 10987417600 1185403520 12.641 100 96 190 1 1679808208 470116336 24288352
09:03:39 924 8192000000 10520456608 10993545216 1174893392 12.543 100 96 192 0 1620889024 422196128 23799504
09:04:42 6227 8192000000 10522760432 10965471232 1177317744 12.565 100 65 128 0 1624902464 430951232 16633488
09:05:50 16255 8192000000 10479903840 10949427200 1170685392 12.503 100 51 102 1 1603744528 420287888 12771248
09:07:13 10414 8192000000 10522694128 10991575040 1183268160 12.621 100 54 108 0 1643590480 445906048 14416272
09:08:40 12222 8192000000 10448713952 10956685312 1180470144 12.595 99 35 70 0 1564947472 375302320 9175008
09:09:54 25402 8192000000 10468629728 10926465024 1175578704 12.549 100 31 62 1 1588239104 404654224 8006176
09:11:23 35561 8192000000 10406745600 10956124160 1151672048 12.325 99 28 56 0 1549115008 390430592 7012368
09:12:59 34528 8192000000 10271345664 10756177920 1132874304 12.148 98 26 52 2 1382872736 243755728 6242704
09:14:40 9274 8192000000 10189063728 10739523584 1140780768 12.223 100 27 54 0 1310636288 163682256 6173264
09:15:51 24159 8192000000 10160257456 10601099264 1153048080 12.338 100 24 48 0 1257334112 98750160 5535872
09:17:20 104501 8192000000 10196459424 10633957376 1124982192 12.074 98 42 76 0 1262438864 129240688 8215984
09:20:19 86550 8192000000 10100084656 10602725376 1134028080 12.159 99 16 31 0 1222847760 85264224 3555456
09:22:49 60506 8192000000 10063840880 10545049600 1134058192 12.160 100 19 38 1 1224840640 86624768 4157680
09:25:06 70727 8192000000 10051041904 10496065536 1094163216 11.782 96 22 43 0 1121519488 22636464 4719808
09:27:22 64726 8192000000 10086787088 10521632768 1096451584 11.804 97 20 40 1 1141891504 40882896 4557024
09:29:32 92112 8192000000 10101912240 10571698176 1123165136 12.057 99 18 36 1 1191048704 63748896 4134672
09:32:44 85638 8192000000 10036769392 10485248000 1100967056 11.847 97 18 35 1 1128314208 24172032 3175120
09:35:12 32568 8192000000 10070541328 10519064576 1132659920 12.146 100 34 65 3 1182806448 43767968 6378560
09:36:52 71685 8192000000 10067810416 10563485696 1138042224 12.197 100 15 30 0 1207403840 65841824 3519792
09:39:04 70847 8192000000 10132210512 10578489344 1140153536 12.217 99 22 44 0 1227795264 82804416 4837312
09:42:15 35036 8192000000 10122600912 10600792064 1114369872 11.974 98 18 35 0 1214549744 96508384 3671488
09:43:50 57934 8192000000 10123173968 10596380672 1145417664 12.266 100 30 60 0 1207467984 56038960 6011360
09:45:49 51284 8192000000 10140527872 10615816192 1138368320 12.200 99 18 36 0 1236697408 94636784 3692304
09:47:51 34720 8192000000 10125638096 10582904832 1130238832 12.124 99 31 61 1 1224438192 87280208 6919152
09:49:26 45505 8192000000 10126455936 10589487104 1099672608 11.835 96 22 44 2 1172956704 68582400 4701696
09:51:17 48711 8192000000 10106068688 10558853120 1118640080 12.014 99 35 70 0 1217966320 91976896 7349344
09:53:06 118228 8192000000 10123416480 10666381312 1125532288 12.079 99 19 39 0 1217354448 88096224 3725936
09:56:12 157233 8192000000 10080916160 10528817152 1129389552 12.116 99 19 38 2 1180053360 45975360 4688448
10:00:38 84373 8192000000 10099183888 10614890496 1145483056 12.267 100 11 21 1 1197283920 48898464 2902400
10:03:11 59501 8192000000 10109484928 10585145344 1153565552 12.343 100 12 24 1 1216755168 59953568 3236048
10:06:28 54868 8192000000 10077142864 10526380032 1083285216 11.679 94 12 24 0 1137131104 51309968 2535920
10:08:38 70366 8192000000 10083415632 10561695744 1085356736 11.698 96 15 30 0 1174245696 85685888 3203072
10:10:48 50103 8192000000 10098971024 10538532864 1095916496 11.799 97 23 46 1 1184851408 83888528 5046384
10:12:39 30784 8192000000 10088892096 10524135424 1109777472 11.930 99 28 56 1 1161823312 46318400 5727440
10:14:11 47211 8192000000 10052280992 10531115008 1101718992 11.854 98 14 28 0 1156709136 51877792 3112352
10:16:02 43889 8192000000 10108219360 10560901120 1103878720 11.874 98 34 66 1 1161241232 50680096 6682416
10:17:48 71950 8192000000 10087086576 10553978880 1066522416 11.519 94 25 48 1 1168747840 96496832 5728592
10:20:00 33815 8192000000 10081095200 10521792512 1118318640 12.011 99 52 98 0 1158245952 29256688 10670624
10:21:35 70348 8192000000 10113190736 10560180224 1111345488 11.945 98 47 93 1 1172804736 52535104 8924144
10:23:46 67828 8192000000 10079929344 10522521600 1125991216 12.084 100 50 100 0 1178613584 41680816 10941552
10:26:08 29898 8192000000 10131410800 10570174464 1136504960 12.183 100 59 116 1 1217912224 68690464 12716800
10:27:43 49816 8192000000 10083238816 10571747328 1135792208 12.176 100 11 22 0 1186284592 48175536 2316848
10:29:33 39625 8192000000 10111314448 10564079616 1139414336 12.210 100 18 36 0 1199072368 55910944 3747088
10:31:21 81362 8192000000 10104409920 10579955712 1121679584 12.043 98 14 28 0 1200136016 75382864 3073568
10:34:11 28631 8192000000 10099927168 10549538816 1114848144 11.978 98 23 45 0 1188562256 68656064 5058048
10:35:46 27346 8192000000 10114253600 10556968960 1130967776 12.130 99 27 52 1 1208589488 72013888 5607824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-04-2022 10:38:46 to 18-04-2022 10:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-04-2022 10:39:03 to 18-04-2022 10:39:05.

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          203879
MON$OLDEST_ACTIVE               207450
MON$OLDEST_SNAPSHOT             207450
MON$NEXT_TRANSACTION            207450
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             751
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:279: 
:280: mscs-test-161.budzdorov.ru    Mon Apr 18 10:37:20 2022
:281:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:282: 
:283: 
:284: mscs-test-161.budzdorov.ru    Mon Apr 18 10:37:20 2022
:285:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:286: 
:287: 
:288: mscs-test-161.budzdorov.ru    Mon Apr 18 10:37:24 2022
:289:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:290: 
:291: 
:292: mscs-test-161.budzdorov.ru    Mon Apr 18 10:37:25 2022
:293:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:294: 
--- end of diff output ---

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

18.04.22 10:39:06. 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-04-18 10:39:08 No severe PSQL-related problems occured
18.04.22 10:39:10. End of report.