Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.04.2022 07:47
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 16281000 kB
MemAvailable 31554612 kB
Buffers 0 kB
Cached 15452368 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-13 07:46:34
DTS_END                         2022-04-13 07:46:34.0360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46796
TRN_ID                          224817
ATT_ID                          612
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: 9270.01 18277 1112401
customer order: creation 179.67 440 21560
customer order: refuse 14.96 380 1795
order to supplier: creation 47.49 1358 5699
order to supplier: removal 3.35 162775 402
invoice (draft): creation 47.59 3129 5711
invoice (draft): removal 49.09 999 5891
invoice accept: apply 44.03 25744 5284
invoice accept: cancel 44.87 1229 5384
customer reserve: creation 4123.41 243 494809
customer reserve: removal 4408.97 98 529076
realization accept: apply 86.24 266 10349
realization accept: cancel 85.93 165 10311
payment from customer: creation 61.78 167 7413
payment from customer: removal 5.48 96 657
payment to supplier: creation 50.43 180 6051
payment to supplier: removal 3.57 119 428
service: total inventory turnovers 4.04 3740 485
service: total monetary turnovers 8.03 319 964
service: aggregate perf. data 1.07 13555 128
service: refresh index statistics 0.03 150533 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-04-2022 07:48:17 to 13-04-2022 07:48:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9610
TEST_TIME 2 7287
TEST_TIME 3 7239
TEST_TIME 4 6677
TEST_TIME 5 6979
TEST_TIME 6 6558
TEST_TIME 7 6896
TEST_TIME 8 6880
TEST_TIME 9 7004
TEST_TIME 10 6994
TEST_TIME 11 7346
TEST_TIME 12 7582
TEST_TIME 13 7698
TEST_TIME 14 7976
TEST_TIME 15 8065
TEST_TIME 16 7963
TEST_TIME 17 8000
TEST_TIME 18 8441
TEST_TIME 19 8389
TEST_TIME 20 8335
TEST_TIME 21 8369
TEST_TIME 22 8421
TEST_TIME 23 8425
TEST_TIME 24 8493
TEST_TIME 25 8796
TEST_TIME 26 8670
TEST_TIME 27 8929
TEST_TIME 28 9050
TEST_TIME 29 9358
TEST_TIME 30 9756
TEST_TIME 31 10419
TEST_TIME 32 10626
TEST_TIME 33 10626
TEST_TIME 34 10973
TEST_TIME 35 11334
TEST_TIME 36 11392
TEST_TIME 37 11165
TEST_TIME 38 11179
TEST_TIME 39 11110
TEST_TIME 40 10977
TEST_TIME 41 10998
TEST_TIME 42 10898
TEST_TIME 43 10692
TEST_TIME 44 10528
TEST_TIME 45 10444
TEST_TIME 46 10381
TEST_TIME 47 10282
TEST_TIME 48 10350
TEST_TIME 49 10242
TEST_TIME 50 10186
TEST_TIME 51 10123
TEST_TIME 52 10051
TEST_TIME 53 10017
TEST_TIME 54 9988
TEST_TIME 55 9930
TEST_TIME 56 10124
TEST_TIME 57 10042
TEST_TIME 58 9982
TEST_TIME 59 9989
TEST_TIME 60 9907
TEST_TIME 61 9963
TEST_TIME 62 9951
TEST_TIME 63 9899
TEST_TIME 64 10028
TEST_TIME 65 10057
TEST_TIME 66 10031
TEST_TIME 67 10121
TEST_TIME 68 10077
TEST_TIME 69 10047
TEST_TIME 70 10034
TEST_TIME 71 10073
TEST_TIME 72 10088
TEST_TIME 73 10000
TEST_TIME 74 9956
TEST_TIME 75 9864
TEST_TIME 76 9786
TEST_TIME 77 9763
TEST_TIME 78 9769
TEST_TIME 79 9706
TEST_TIME 80 9678
TEST_TIME 81 9609
TEST_TIME 82 9581
TEST_TIME 83 9587
TEST_TIME 84 9546
TEST_TIME 85 9555
TEST_TIME 86 9523
TEST_TIME 87 9552
TEST_TIME 88 9560
TEST_TIME 89 9539
TEST_TIME 90 9569
TEST_TIME 91 9532
TEST_TIME 92 9692
TEST_TIME 93 9654
TEST_TIME 94 9645
TEST_TIME 95 9637
TEST_TIME 96 9622
TEST_TIME 97 9586
TEST_TIME 98 9524
TEST_TIME 99 9505
TEST_TIME 100 9450
TEST_TIME 101 9393
TEST_TIME 102 9376
TEST_TIME 103 9348
TEST_TIME 104 9343
TEST_TIME 105 9304
TEST_TIME 106 9301
TEST_TIME 107 9273
TEST_TIME 108 9274
TEST_TIME 109 9262
TEST_TIME 110 9238
TEST_TIME 111 9258
TEST_TIME 112 9250
TEST_TIME 113 9285
TEST_TIME 114 9374
TEST_TIME 115 9495
TEST_TIME 116 9440
TEST_TIME 117 9385
TEST_TIME 118 9381
TEST_TIME 119 9342
TEST_TIME 120 9296

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-04-2022 07:48:21 to 13-04-2022 07:48:24.

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 1566187 1566187 0 0.00 0 39659 14 0 0
doc_list_biud 1566174 1566174 0 0.00 0 38863 39 0 0
sp_add_invoice_to_stock 5367 5284 83 1.54 57 171432 25744 0 83
sp_cancel_adding_invoice 5384 5384 0 0.00 6 116454 1229 0 0
sp_cancel_client_order 1795 1795 0 0.00 5 10168 380 0 0
sp_cancel_customer_reserve 529076 529076 0 0.00 1 30532 98 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 4 1652 96 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 4 6670 120 0 0
sp_cancel_supplier_invoice 5897 5891 6 0.10 3 27233 999 0 6
sp_cancel_supplier_order 402 402 0 0.00 11 1414115 162775 0 0
sp_cancel_write_off 10311 10311 0 0.00 2 9844 165 0 0
sp_client_order 21560 21560 0 0.00 6 30597 441 0 0
sp_customer_reserve 494809 494809 0 0.00 1 129819 243 0 0
sp_fill_shopping_cart 32959 32959 0 0.00 1 16382 82 0 0
sp_fill_shopping_cart_clo_res 494772 494772 0 0.00 0 19007 17 0 0
sp_get_clo_for_invoice 5236 5236 0 0.00 0 28803 257 0 0
sp_kill_cost_storno 16780 16780 0 0.00 0 6660 64 0 0
sp_kill_qstorno_ret_qs2qd 1074756 1074756 0 0.00 0 30521 43 0 0
sp_kill_qty_storno 568442 568442 0 0.00 0 38863 98 0 0
sp_lock_selected_doc 21418 21418 0 0.00 0 7326 7 0 0
sp_make_cost_storno 29047 29047 0 0.00 0 16627 77 0 0
sp_multiply_rows_for_pdistr 29046 29046 0 0.00 0 8620 34 0 0
sp_multiply_rows_for_qdistr 21560 21560 0 0.00 0 10899 155 0 0
sp_pay_from_customer 7413 7413 0 0.00 6 8362 168 0 0
sp_pay_to_supplier 6051 6051 0 0.00 7 11351 180 0 0
sp_payment_common 13460 13460 0 0.00 2 6223 42 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 10159 307 0 0
sp_qd_handle_on_invoice_upd_sts 10619 10619 0 0.00 0 38862 496 0 0
sp_qd_handle_on_reserve_upd_sts 10348 10348 0 0.00 0 19296 60 0 0
sp_reserve_write_off 10349 10349 0 0.00 9 19365 266 0 0
sp_supplier_invoice 5711 5711 0 0.00 10 96689 3129 0 0
sp_supplier_order 5699 5699 0 0.00 8 23168 1358 0 0
srv_aggregate_perf_data 128 128 0 0.00 2122 170876 13555 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 894 175090 35863 0 0
srv_find_qd_qs_mism 1060049 1060049 0 0.00 0 39656 11 0 0
srv_make_invnt_saldo 485 485 0 0.00 79 55501 3740 0 0
srv_make_money_saldo 964 964 0 0.00 1 12121 320 0 0
srv_recalc_idx_stat 4 4 0 0.00 38484 263885 150534 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5288 5288 0 0.00 1 13191 937 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 1 6219 466 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 3624 27 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 2830 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 810 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1925 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 1354 36 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 1 101152 6889 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 3418 21 0 0
t$perf-norm:sp_client_order 21560 21560 0 0.00 0 6524 36 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 2022 16 0 0
t$perf-norm:sp_pay_from_customer 7413 7413 0 0.00 0 3406 17 0 0
t$perf-norm:sp_pay_to_supplier 6051 6051 0 0.00 0 2623 18 0 0
t$perf-norm:sp_reserve_write_off 10349 10349 0 0.00 0 6231 24 0 0
t$perf-norm:sp_supplier_invoice 5711 5711 0 0.00 0 5670 28 0 0
t$perf-norm:sp_supplier_order 5699 5699 0 0.00 0 2114 29 0 0
t$perf-norm:srv_aggregate_perf_data 128 128 0 0.00 0 436 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 574 28 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 726 14 0 0
t$perf-norm:srv_make_money_saldo 964 964 0 0.00 0 847 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 212 75 0 0
v_add_invoice_to_stock 10625 10460 165 1.55 0 6021 23 0 165
v_all_customers 39584 39584 0 0.00 0 154 4 0 0
v_all_suppliers 31752 31752 0 0.00 0 163 4 0 0
v_all_wares 43110 43110 0 0.00 0 153 4 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 654 14 0 0
v_cancel_client_order 3590 3590 0 0.00 0 2134 11 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 1523 12 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 1897 13 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 1309 11 0 0
v_cancel_supplier_invoice 850 838 12 1.41 0 2657 28 0 12
v_cancel_supplier_order 804 804 0 0.00 0 1746 16 0 0
v_cancel_write_off 1962 1962 0 0.00 0 2463 23 0 0
v_max_id_clo_ord 5698 5698 0 0.00 0 755 5 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 3143 19 0 0
v_max_id_ord_sup 5704 5704 0 0.00 0 8579 8 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 163 7 0 0
v_max_non_paid_realizn 3742 3742 0 0.00 0 525 7 0 0
v_min_id_clo_ord 5698 5698 0 0.00 0 3795 10 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 6370 19 0 0
v_min_id_ord_sup 5704 5704 0 0.00 0 6832 10 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 5236 14 0 0
v_min_non_paid_realizn 7412 7412 0 0.00 0 5234 14 0 0
v_our_firm 10866 10866 0 0.00 0 62 4 0 0
v_reserve_write_off 20696 20696 0 0.00 0 3158 11 0 0
x_lock_depdocs_on_canc_invoice 10856 10856 0 0.00 0 26238 121 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 11475 328 0 0
x_make_qstorno_1000_1200 5699 5699 0 0.00 3 23124 1184 0 0
x_make_qstorno_1200_2000 5711 5711 0 0.00 4 96676 2916 0 0
x_make_qstorno_2100_3300 494807 494807 0 0.00 0 129810 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-04-2022 07:48:25 to 13-04-2022 07:48:29.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:16:37 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:38 30865 8192000000 9732102592 10029907968 807738016 8.975 100 93 186 1 838215104 7997104 22479984
05:19:12 25269 8192000000 9768570096 10075566080 841788464 9.318 100 96 192 0 877551136 13199232 22563440
05:20:50 16469 8192000000 9804876304 10127740928 869096512 9.591 100 79 158 1 913919760 26525184 18298064
05:22:12 9072 8192000000 9845439648 10181013504 910139504 9.999 100 70 138 0 959795472 34322240 15333728
05:23:25 5921 8192000000 9921261360 10266370048 960779152 10.497 100 91 176 0 1042572368 63651312 18141904
05:24:33 1987 8192000000 9960251488 10314719232 996313344 10.843 100 91 175 2 1077740560 63341216 18086000
05:25:37 1105 8192000000 9993321920 10358439936 1023159392 11.103 100 95 176 2 1121763440 79572624 19031424
05:26:41 792 8192000000 10033110576 10404704256 1049158736 11.353 100 96 193 2 1156275776 87722736 19394304
05:27:43 1062 8192000000 10046662000 10422345728 1062286032 11.478 100 100 189 0 1165418128 83730240 19401856
05:28:45 1175 8192000000 10036816496 10411429888 1062413488 11.480 100 94 179 1 1160740672 80268240 18058944
05:29:47 931 8192000000 10031447312 10416082944 1070213264 11.554 100 83 162 1 1153872576 67727616 15931696
05:30:51 1401 8192000000 10032544864 10417135616 1074336256 11.593 100 89 174 3 1158366608 65589520 18440832
05:31:52 1075 8192000000 10041500560 10428080128 1084208544 11.688 100 90 171 0 1165912224 64174240 17529440
05:32:54 1131 8192000000 10058731392 10444845056 1091804640 11.760 100 96 191 2 1177973120 66936880 19231600
05:33:56 7461 8192000000 10066949184 10462404608 1095131920 11.791 100 71 136 1 1195331952 85389920 14810112
05:35:05 1048 8192000000 10072654432 10462744576 1097750816 11.816 100 94 185 1 1190202560 73911168 18540576
05:36:07 3757 8192000000 10037912592 10443272192 1103883904 11.874 100 61 121 0 1162544112 46935984 11724224
05:37:12 1550 8192000000 10064160352 10453016576 1104052320 11.876 100 84 160 2 1183527504 63439952 16035232
05:38:15 1607 8192000000 10053042928 10450743296 1089075616 11.734 100 77 151 0 1171841104 66774368 15991120
05:39:17 1252 8192000000 10068407520 10454986752 1100821840 11.845 100 93 181 1 1189352208 70414352 18116016
05:40:19 944 8192000000 10016011328 10400260096 1064991696 11.504 99 84 161 1 1139812128 57244352 17576080
05:41:21 1120 8192000000 10038581760 10421731328 1073442208 11.585 100 91 175 0 1153130608 61306400 18382000
05:42:24 1381 8192000000 10011807136 10391326720 1052647584 11.386 100 86 164 1 1130140464 61087952 16404928
05:43:27 946 8192000000 10000520160 10373210112 1037621664 11.242 100 91 175 1 1120550112 64265376 18663072
05:44:29 1202 8192000000 9985942000 10354819072 1034377376 11.211 100 96 189 0 1106619488 53979344 18262768
05:45:32 938 8192000000 10002881472 10377678848 1045064208 11.313 100 90 176 1 1122262080 57263760 19934112
05:46:35 1323 8192000000 9990313760 10363158528 1043249216 11.296 100 95 178 3 1114881440 54469920 17162304
05:47:42 11809 8192000000 10014028576 10401697792 1057587200 11.433 99 55 108 1 1132518512 64344352 10586960
05:48:59 6333 8192000000 10058558560 10446143488 1084137264 11.687 100 76 148 0 1170567536 70934672 15495600
05:50:11 1188 8192000000 10095699168 10481496064 1106094672 11.895 100 94 183 1 1215311056 89533632 19682752
05:51:15 2693 8192000000 10146026608 10533416960 1116664064 11.995 100 85 166 0 1266098720 132123200 17311456
05:52:22 4545 8192000000 10162897184 10562568192 1121990224 12.046 100 74 144 1 1277865152 139768160 16106768
05:53:36 8720 8192000000 10171032352 10563076096 1133304224 12.153 100 76 146 0 1290839136 141542688 15992224
05:54:48 3625 8192000000 10199022736 10594791424 1142391984 12.238 100 77 152 3 1318879520 160065648 16421888
05:55:56 2855 8192000000 10243283248 10654683136 1148005344 12.291 100 81 156 1 1361236368 195172832 18058192
05:57:05 955 8192000000 10278824624 10683666432 1159159168 12.395 100 96 183 1 1391395344 211571760 20664416
05:58:09 2393 8192000000 10255489392 10659069952 1158257200 12.387 100 83 157 2 1363790160 187913840 17619120
05:59:13 1216 8192000000 10306412144 10706292736 1173077040 12.526 100 97 191 0 1413076288 220392736 19606512
06:00:17 1824 8192000000 10319081216 10743377920 1175250912 12.546 100 91 175 1 1432279344 236980720 20047712
06:01:22 878 8192000000 10364507216 10766041088 1179731376 12.588 100 95 179 0 1471950704 270498720 21720608
06:02:26 1319 8192000000 10372247088 10777456640 1187023888 12.656 100 89 175 0 1481870592 274939280 19907424
06:03:30 1096 8192000000 10395828864 10830036992 1189379856 12.678 100 93 180 1 1498046752 286753056 21913840
06:04:33 6166 8192000000 10405125136 10837938176 1182250992 12.611 100 70 136 1 1511738208 313161312 16325904
06:05:46 3171 8192000000 10412387184 10831253504 1186233296 12.648 100 64 124 2 1522804080 322022704 14548080
06:06:54 5846 8192000000 10425816800 10873458688 1177615680 12.568 99 60 116 1 1530033328 338281952 14135696
06:08:05 1191 8192000000 10494532016 10908352512 1188390048 12.668 100 96 188 1 1605736368 393521104 23825216
06:09:09 1032 8192000000 10502211712 10931593216 1185246976 12.639 100 97 191 0 1625838016 417514880 23076160
06:10:13 1012 8192000000 10565320320 10994827264 1197268080 12.751 100 100 194 3 1667448336 446883152 23297104
06:11:17 1267 8192000000 10557193008 10995146752 1192770096 12.709 100 79 158 1 1661600224 449277088 19553040
06:12:19 3481 8192000000 10543909824 10987024384 1168142320 12.479 99 64 128 1 1641540336 456995216 16402800
06:13:25 12336 8192000000 10522721072 10991943680 1169316416 12.490 100 52 102 0 1670119200 487716160 13086624
06:14:42 6908 8192000000 10580794224 11023966208 1161715968 12.419 100 47 94 0 1688328752 514468976 12143808
06:15:56 10297 8192000000 10431333936 10962231296 1145923024 12.271 100 34 68 0 1540594720 386090496 8581200
06:17:09 7747 8192000000 10354426016 10825412608 1164224384 12.443 100 48 94 1 1467518320 292446128 10847808
06:18:18 49281 8192000000 10274816848 10739388416 1123414672 12.059 97 20 40 0 1354272640 226339408 4518560
06:20:27 10441 8192000000 10183132544 10657976320 1146549872 12.277 100 53 104 0 1285455952 126884608 12021472
06:21:40 74315 8192000000 10098512208 10598744064 1114904240 11.979 97 12 24 0 1201963104 84384448 2674416
06:24:00 46314 8192000000 10078399904 10549551104 1125043056 12.075 99 27 52 2 1194309168 63110304 6155808
06:25:47 68454 8192000000 10075776336 10527035392 1144338976 12.256 100 18 34 3 1169245872 21670336 3236560
06:28:24 175087 8192000000 10101001872 10581950464 1126684000 12.090 98 21 42 1 1193578848 62350272 4544576
06:32:30 74317 8192000000 10119385888 10574819328 1146165056 12.273 100 22 43 0 1220008384 68517024 5326304
06:34:46 24160 8192000000 10077228112 10529824768 1111433184 11.946 96 14 28 1 1146583536 32264992 2885360
06:36:12 21954 8192000000 10099622656 10541277184 1116414928 11.993 98 48 94 0 1178290464 51167536 10708000
06:37:48 135082 8192000000 10186807408 10673397760 1142091248 12.235 99 30 60 0 1243559600 94901168 6567184
06:41:36 54917 8192000000 10145412944 10625490944 1125218048 12.076 98 15 30 2 1238915200 110107248 3589904
06:43:35 66276 8192000000 10077400960 10568888320 1141514144 12.230 100 15 30 0 1186592656 42141936 2936576
06:45:42 64472 8192000000 10108240240 10548092928 1128912016 12.111 98 28 53 0 1182109072 48167584 5029472
06:47:47 94214 8192000000 10156588160 10603438080 1150598736 12.315 99 17 34 1 1242127664 87477280 4051648
06:50:56 59840 8192000000 10090520032 10535366656 1134410560 12.163 99 24 46 1 1181201680 41606048 5185072
06:52:56 74612 8192000000 10087669792 10567520256 1089234208 11.735 95 16 32 1 1166670848 73906112 3530528
06:55:21 35446 8192000000 10082634816 10518106112 1087875152 11.722 97 48 95 0 1143529824 45989920 9664752
06:57:28 67851 8192000000 10071974544 10545700864 1071782448 11.569 95 14 28 1 1139694368 64277024 3634896
06:59:36 103306 8192000000 10046820096 10503249920 1069753680 11.550 96 23 46 0 1117475360 42803536 4918144
07:02:32 25382 8192000000 10086864800 10508251136 1119045584 12.018 100 69 133 0 1172316448 39521168 13749696
07:04:00 61496 8192000000 10063261888 10533175296 1113351664 11.964 99 13 26 1 1195526400 78995248 3179488
07:06:12 76107 8192000000 10057226048 10502377472 1076524640 11.614 96 9 18 0 1118462640 39806928 2131072
07:08:50 71420 8192000000 10041638976 10477940736 1048485296 11.346 94 15 28 1 1090969760 39714656 2769808
07:11:02 55991 8192000000 10051771152 10485706752 1089975696 11.742 97 31 61 1 1125922192 29554112 6392384
07:13:07 53973 8192000000 10076213680 10520379392 1070184016 11.554 96 27 54 0 1151151296 75373296 5593984
07:15:08 32766 8192000000 10113323200 10557878272 1113512272 11.966 100 38 75 0 1199554672 78476912 7565488
07:16:55 47351 8192000000 10138498576 10582036480 1109719376 11.930 98 32 65 1 1218732464 101981920 7031168
07:18:44 54534 8192000000 10067566496 10535792640 1108904400 11.922 98 16 32 1 1201485280 89346672 3234208
07:20:47 20570 8192000000 10088060064 10558365696 1133085440 12.150 100 29 58 0 1198573136 59276304 6211392
07:22:11 75223 8192000000 10119965248 10561773568 1139577504 12.212 100 56 112 0 1221437360 70216800 11643056
07:24:27 7038 8192000000 10061837984 10490052608 1121420048 12.040 98 62 112 4 1152022704 17207664 13394992
07:26:08 74469 8192000000 10064922960 10520788992 1134762272 12.166 100 11 22 0 1179010944 41489600 2759072
07:28:29 71530 8192000000 10104984464 10571636736 1146195888 12.274 100 14 29 0 1203894304 54198560 3499856
07:30:41 34766 8192000000 10079859168 10520281088 1142067824 12.235 100 18 35 1 1170835120 24948480 3818816
07:32:20 21519 8192000000 10113124256 10563964928 1148674336 12.297 99 34 67 3 1202963232 47300720 6988176
07:33:44 17112 8192000000 10136282928 10583076864 1138149760 12.198 99 31 61 2 1221445472 75822800 7472912
07:35:04 14306 8192000000 10170243376 10609098752 1154570048 12.352 99 38 77 0 1252072656 88922160 8580448
07:36:19 31151 8192000000 10191942032 10628153344 1123830960 12.063 97 40 78 3 1263327232 130939824 8556448
07:37:58 31050 8192000000 10252183472 10685337600 1164330496 12.444 100 54 100 2 1334473472 158981792 11161184
07:39:29 81923 8192000000 10184493056 10734501888 1114798064 11.978 96 19 36 1 1299506960 180292464 4416432
07:42:09 93028 8192000000 10083719392 10538106880 1118406768 12.012 98 18 36 0 1171976208 49603216 3966224
07:44:54 43902 8192000000 10052352416 10531422208 1106572864 11.900 98 10 18 1 1139540544 31249536 1718144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-04-2022 07:48:31 to 13-04-2022 07:48:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-04-2022 07:48:48 to 13-04-2022 07:48:50.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          224147
MON$OLDEST_ACTIVE               224937
MON$OLDEST_SNAPSHOT             224937
MON$NEXT_TRANSACTION            224937
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                       2077472
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Apr 13 07:46:36 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Apr 13 07:46:37 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Apr 13 07:46:47 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Wed Apr 13 07:46:47 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

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