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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15891720 kB
MemAvailable 31115036 kB
Buffers 0 kB
Cached 15840428 kB
SwapCached 2528 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-07-15 13:37:39
DTS_END                         2022-07-15 13:37:19.6810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54734
TRN_ID                          207071
ATT_ID                          259
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: 8552.81 31402 1026337
customer order: creation 150.09 497 18011
customer order: refuse 13.51 488 1621
order to supplier: creation 41.33 1484 4960
order to supplier: removal 3.26 178050 391
invoice (draft): creation 40.17 3833 4821
invoice (draft): removal 48.44 1033 5813
invoice accept: apply 37.85 29158 4542
invoice accept: cancel 44.16 1343 5299
customer reserve: creation 3546.83 272 425619
customer reserve: removal 4358.79 104 523055
realization accept: apply 74.53 324 8943
realization accept: cancel 76.80 189 9216
payment from customer: creation 52.91 207 6349
payment from customer: removal 5.21 170 625
payment to supplier: creation 44.27 231 5312
payment to supplier: removal 3.03 154 363
service: total inventory turnovers 3.48 4832 418
service: total monetary turnovers 7.27 463 872
service: aggregate perf. data 0.84 18510 101
service: refresh index statistics 0.05 386688 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-07-2022 13:39:18 to 15-07-2022 13:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6266
TEST_TIME 2 6652
TEST_TIME 3 5819
TEST_TIME 4 5679
TEST_TIME 5 5926
TEST_TIME 6 5752
TEST_TIME 7 5691
TEST_TIME 8 5743
TEST_TIME 9 5753
TEST_TIME 10 5937
TEST_TIME 11 5970
TEST_TIME 12 5956
TEST_TIME 13 6100
TEST_TIME 14 6442
TEST_TIME 15 6686
TEST_TIME 16 6812
TEST_TIME 17 6910
TEST_TIME 18 6969
TEST_TIME 19 6978
TEST_TIME 20 7009
TEST_TIME 21 7047
TEST_TIME 22 7126
TEST_TIME 23 7148
TEST_TIME 24 7198
TEST_TIME 25 7451
TEST_TIME 26 7457
TEST_TIME 27 7658
TEST_TIME 28 7817
TEST_TIME 29 7952
TEST_TIME 30 8299
TEST_TIME 31 8251
TEST_TIME 32 8416
TEST_TIME 33 8671
TEST_TIME 34 8861
TEST_TIME 35 9019
TEST_TIME 36 9285
TEST_TIME 37 9524
TEST_TIME 38 9814
TEST_TIME 39 9911
TEST_TIME 40 10058
TEST_TIME 41 9916
TEST_TIME 42 9842
TEST_TIME 43 9783
TEST_TIME 44 9622
TEST_TIME 45 9701
TEST_TIME 46 9773
TEST_TIME 47 9709
TEST_TIME 48 9655
TEST_TIME 49 9560
TEST_TIME 50 9492
TEST_TIME 51 9445
TEST_TIME 52 9322
TEST_TIME 53 9359
TEST_TIME 54 9220
TEST_TIME 55 9170
TEST_TIME 56 9058
TEST_TIME 57 9075
TEST_TIME 58 9048
TEST_TIME 59 9029
TEST_TIME 60 8941
TEST_TIME 61 8910
TEST_TIME 62 8817
TEST_TIME 63 8728
TEST_TIME 64 8728
TEST_TIME 65 8650
TEST_TIME 66 8703
TEST_TIME 67 8652
TEST_TIME 68 8624
TEST_TIME 69 8637
TEST_TIME 70 8611
TEST_TIME 71 8537
TEST_TIME 72 8474
TEST_TIME 73 8564
TEST_TIME 74 8564
TEST_TIME 75 8532
TEST_TIME 76 8583
TEST_TIME 77 8570
TEST_TIME 78 8545
TEST_TIME 79 8537
TEST_TIME 80 8574
TEST_TIME 81 8700
TEST_TIME 82 8820
TEST_TIME 83 8786
TEST_TIME 84 8801
TEST_TIME 85 8828
TEST_TIME 86 8839
TEST_TIME 87 8940
TEST_TIME 88 8903
TEST_TIME 89 8892
TEST_TIME 90 8808
TEST_TIME 91 8804
TEST_TIME 92 8753
TEST_TIME 93 8765
TEST_TIME 94 8779
TEST_TIME 95 8727
TEST_TIME 96 8681
TEST_TIME 97 8659
TEST_TIME 98 8645
TEST_TIME 99 8643
TEST_TIME 100 8641
TEST_TIME 101 8640
TEST_TIME 102 8623
TEST_TIME 103 8604
TEST_TIME 104 8651
TEST_TIME 105 8654
TEST_TIME 106 8646
TEST_TIME 107 8669
TEST_TIME 108 8673
TEST_TIME 109 8768
TEST_TIME 110 8753
TEST_TIME 111 8768
TEST_TIME 112 8803
TEST_TIME 113 8846
TEST_TIME 114 8779
TEST_TIME 115 8759
TEST_TIME 116 8708
TEST_TIME 117 8676
TEST_TIME 118 8660
TEST_TIME 119 8641
TEST_TIME 120 8577

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-07-2022 13:39:21 to 15-07-2022 13:39:25.

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 1418016 1418016 0 0.00 0 20567 14 0 0
doc_list_biud 1418014 1418014 0 0.00 0 81386 45 0 0
sp_add_invoice_to_stock 4673 4542 131 2.80 45 168067 29158 0 131
sp_cancel_adding_invoice 5299 5299 0 0.00 5 111949 1344 0 0
sp_cancel_client_order 1621 1621 0 0.00 7 11891 489 0 0
sp_cancel_customer_reserve 523055 523055 0 0.00 1 81412 104 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 4 4392 170 0 0
sp_cancel_pay_to_supplier 363 363 0 0.00 4 4372 154 0 0
sp_cancel_supplier_invoice 5830 5813 17 0.29 3 44380 1033 0 17
sp_cancel_supplier_order 391 391 0 0.00 10 1559468 178051 0 0
sp_cancel_write_off 9216 9216 0 0.00 2 15665 190 0 0
sp_client_order 18011 18011 0 0.00 6 32784 497 0 0
sp_customer_reserve 425619 425619 0 0.00 0 59176 272 0 0
sp_fill_shopping_cart 27784 27784 0 0.00 1 45986 94 0 0
sp_fill_shopping_cart_clo_res 425589 425589 0 0.00 0 17807 21 0 0
sp_get_clo_for_invoice 4510 4510 0 0.00 0 33856 344 0 0
sp_kill_cost_storno 15503 15503 0 0.00 0 15603 88 0 0
sp_kill_qstorno_ret_qs2qd 1061530 1061530 0 0.00 0 81382 45 0 0
sp_kill_qty_storno 558846 558846 0 0.00 0 81386 105 0 0
sp_lock_selected_doc 18662 18662 0 0.00 0 9565 9 0 0
sp_make_cost_storno 25113 25113 0 0.00 0 14418 89 0 0
sp_multiply_rows_for_pdistr 25112 25112 0 0.00 0 20549 47 0 0
sp_multiply_rows_for_qdistr 18011 18011 0 0.00 0 21732 168 0 0
sp_pay_from_customer 6349 6349 0 0.00 5 20583 207 0 0
sp_pay_to_supplier 5312 5312 0 0.00 6 15482 232 0 0
sp_payment_common 11661 11661 0 0.00 2 15080 52 0 0
sp_qd_handle_on_cancel_clo 1621 1621 0 0.00 1 11884 404 0 0
sp_qd_handle_on_invoice_upd_sts 9807 9807 0 0.00 0 24121 610 0 0
sp_qd_handle_on_reserve_upd_sts 8943 8943 0 0.00 0 15568 80 0 0
sp_reserve_write_off 8943 8943 0 0.00 9 15627 325 0 0
sp_supplier_invoice 4821 4821 0 0.00 10 118382 3834 0 0
sp_supplier_order 4960 4960 0 0.00 7 51353 1485 0 0
srv_aggregate_perf_data 101 101 0 0.00 2393 143072 18511 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 759 182550 35539 0 0
srv_find_qd_qs_mism 982114 982114 0 0.00 0 19112 10 0 0
srv_make_invnt_saldo 418 418 0 0.00 44 83282 4832 0 0
srv_make_money_saldo 872 872 0 0.00 1 12616 464 0 0
srv_recalc_idx_stat 6 6 0 0.00 195748 584190 386689 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4546 4546 0 0.00 1 11097 1083 0 0
t$perf-norm:sp_cancel_adding_invoice 338 338 0 0.00 1 5758 635 0 0
t$perf-norm:sp_cancel_client_order 1621 1621 0 0.00 0 1764 33 0 0
t$perf-norm:sp_cancel_customer_reserve 608 608 0 0.00 0 3161 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 1711 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 363 363 0 0.00 0 406 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 348 348 0 0.00 0 1083 46 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 95627 7378 0 0
t$perf-norm:sp_cancel_write_off 919 919 0 0.00 0 1320 22 0 0
t$perf-norm:sp_client_order 18011 18011 0 0.00 0 11016 46 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 1403 16 0 0
t$perf-norm:sp_pay_from_customer 6349 6349 0 0.00 0 3587 23 0 0
t$perf-norm:sp_pay_to_supplier 5312 5312 0 0.00 0 4823 24 0 0
t$perf-norm:sp_reserve_write_off 8943 8943 0 0.00 0 12279 28 0 0
t$perf-norm:sp_supplier_invoice 4821 4821 0 0.00 0 5688 42 0 0
t$perf-norm:sp_supplier_order 4960 4960 0 0.00 0 2809 38 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 359 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 74 5 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 821 22 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 804 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 243 101 0 0
v_add_invoice_to_stock 9274 9012 262 2.82 0 9870 31 0 262
v_all_customers 33230 33230 0 0.00 0 86 4 0 0
v_all_suppliers 27428 27428 0 0.00 0 102 5 0 0
v_all_wares 36022 36022 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 676 676 0 0.00 0 475 16 0 0
v_cancel_client_order 3242 3242 0 0.00 0 1770 13 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 2567 19 0 0
v_cancel_customer_reserve 1216 1216 0 0.00 0 9582 19 0 0
v_cancel_payment_to_supplier 726 726 0 0.00 0 4331 19 0 0
v_cancel_supplier_invoice 730 696 34 4.65 0 1848 31 0 34
v_cancel_supplier_order 782 782 0 0.00 0 812 13 0 0
v_cancel_write_off 1838 1838 0 0.00 0 2412 26 0 0
v_max_id_clo_ord 4960 4960 0 0.00 0 1878 6 0 0
v_max_id_clo_res 928 928 0 0.00 0 7105 16 0 0
v_max_id_ord_sup 4813 4813 0 0.00 0 1920 6 0 0
v_max_non_paid_invoice 1371 1371 0 0.00 0 327 9 0 0
v_max_non_paid_realizn 3246 3246 0 0.00 0 358 8 0 0
v_min_id_clo_ord 4960 4960 0 0.00 0 2074 9 0 0
v_min_id_clo_res 928 928 0 0.00 0 3345 22 0 0
v_min_id_ord_sup 4813 4813 0 0.00 0 8495 17 0 0
v_min_non_paid_invoice 5312 5312 0 0.00 0 6840 24 0 0
v_min_non_paid_realizn 6349 6349 0 0.00 0 15055 20 0 0
v_our_firm 8998 8998 0 0.00 0 63 5 0 0
v_reserve_write_off 17886 17886 0 0.00 0 9448 13 0 0
x_lock_depdocs_on_canc_invoice 10764 10764 0 0.00 0 12388 151 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 10789 453 0 0
x_make_qstorno_1000_1200 4960 4960 0 0.00 3 51271 1297 0 0
x_make_qstorno_1200_2000 4821 4821 0 0.00 4 118000 3561 0 0
x_make_qstorno_2100_3300 425619 425619 0 0.00 0 47258 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-07-2022 13:39:26 to 15-07-2022 13:39:30.

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:22 4 8192000000 8800672928 8812679168 5971152 0.072 3 1 2 0 6072352 65424 35776
11:08:23 25442 8192000000 9724565712 10023829504 801745824 8.914 100 88 176 0 830470272 7526560 21197888
11:09:54 24353 8192000000 9767044064 10073456640 838878112 9.288 100 93 186 0 873837376 12763056 22196208
11:11:25 15389 8192000000 9797932704 10115612672 859132640 9.491 100 84 164 1 901843840 23264416 19446784
11:12:45 6724 8192000000 9854817504 10185207808 901614704 9.914 100 81 162 0 963594896 43922368 18057824
11:13:56 3463 8192000000 9903786720 10244780032 948632080 10.378 100 84 164 1 1013611648 46993584 17985984
11:15:04 2783 8192000000 9961226240 10309603328 983790320 10.721 100 86 170 1 1077239504 75994832 17454352
11:16:10 1191 8192000000 10019672320 10379616256 1010963952 10.985 100 91 180 0 1131070416 101870192 18236272
11:17:12 1674 8192000000 9994883504 10358550528 1032506176 11.193 100 90 169 2 1117300448 68427776 16366496
11:18:16 825 8192000000 10018053168 10390421504 1050782096 11.368 100 88 167 0 1136139664 68186528 17171040
11:19:18 837 8192000000 10038974416 10415452160 1060584192 11.462 100 96 184 0 1158325136 80281216 17459728
11:20:21 1016 8192000000 10042624944 10421493760 1067319376 11.526 100 99 188 3 1163638768 76772880 19546512
11:21:23 1062 8192000000 10029983872 10409537536 1082257376 11.669 100 95 175 1 1150933920 50553376 18123168
11:22:25 1163 8192000000 10053571888 10441658368 1086976192 11.714 100 95 184 1 1174356912 69202592 18178128
11:23:28 1097 8192000000 10062752608 10452574208 1091080896 11.753 100 96 177 2 1183702784 75621008 17000880
11:24:31 1084 8192000000 10066963712 10460008448 1097792640 11.817 100 87 168 1 1189561984 74344768 17424576
11:25:32 1136 8192000000 10057709488 10450042880 1100166672 11.839 100 95 176 3 1178917136 61046976 17703488
11:26:35 1227 8192000000 10071450528 10459320320 1104709552 11.882 100 96 187 1 1193056048 69577072 18769424
11:27:37 1025 8192000000 10058694848 10450427904 1103597904 11.872 100 87 162 2 1180534960 59286032 17651024
11:28:39 1203 8192000000 10051607424 10440949760 1101257728 11.850 100 87 164 2 1172309616 54991472 16060416
11:29:41 2319 8192000000 10039967104 10436349952 1092231360 11.764 100 77 146 2 1167342256 60652880 14458016
11:30:44 5074 8192000000 10008611376 10396041216 1074331648 11.593 99 72 122 7 1131471152 45068560 12070944
11:31:50 1904 8192000000 10013519920 10397741056 1061865792 11.474 100 69 127 2 1134099424 60903504 11330128
11:32:53 870 8192000000 9992538320 10368323584 1052273360 11.382 100 90 167 2 1110629696 39876416 18479920
11:33:55 3172 8192000000 9994604400 10373476352 1036445120 11.230 99 69 126 1 1114353872 64953920 12954832
11:35:00 7389 8192000000 9973424736 10358988800 1045814368 11.321 100 55 106 0 1105435168 49434736 10186064
11:36:08 6196 8192000000 10001010048 10378391552 1044784464 11.311 100 73 137 2 1118950800 59735664 14430672
11:37:16 6054 8192000000 9997258944 10378371072 1046920464 11.331 100 65 119 1 1114433616 55516864 11996288
11:38:31 3656 8192000000 10019496768 10400587776 1060729168 11.463 100 75 148 1 1134211904 57938720 15544016
11:39:44 4643 8192000000 10029245264 10415800320 1069296432 11.545 100 71 126 2 1147726672 65731440 12698800
11:41:01 12190 8192000000 10026346128 10424217600 1067732752 11.530 100 70 137 1 1148923792 67504480 13686560
11:42:21 4921 8192000000 10035641488 10425942016 1080752992 11.655 100 85 163 3 1158441312 61940592 15747728
11:43:28 22680 8192000000 10057437104 10457874432 1091896144 11.761 100 49 95 0 1176530928 74724112 9910672
11:44:56 6769 8192000000 10108272384 10501726208 1106635888 11.901 100 71 139 1 1229806848 108405216 14765744
11:46:06 2328 8192000000 10147512816 10543910912 1116530512 11.994 100 89 169 0 1254144864 120669488 16944864
11:47:13 2363 8192000000 10148579200 10553696256 1131938704 12.140 100 70 122 1 1265691216 120343584 13408928
11:48:24 2201 8192000000 10203223696 10598928384 1143689472 12.250 100 92 175 2 1313932896 151370032 18873392
11:49:33 2183 8192000000 10228969120 10630877184 1156966272 12.375 100 92 183 1 1347742688 170549952 20226464
11:50:39 831 8192000000 10273500288 10671910912 1171153056 12.508 100 95 189 2 1383166944 191018704 20995184
11:51:46 4409 8192000000 10240486400 10639089664 1164305712 12.444 99 83 161 2 1350719440 167032432 19381296
11:52:54 1542 8192000000 10275969760 10688757760 1183294144 12.621 100 88 170 1 1384686160 181827152 19564864
11:53:58 774 8192000000 10292364848 10703052800 1189938432 12.683 100 99 191 0 1402348928 191254176 21156320
11:55:05 878 8192000000 10346249856 10751094784 1200949792 12.785 100 99 189 1 1450949600 229589952 20409856
11:56:09 1082 8192000000 10357370976 10771861504 1204283472 12.816 100 96 187 1 1463457360 237147600 22026288
11:57:13 1558 8192000000 10397136688 10816565248 1216978160 12.934 100 95 182 1 1505373328 267319152 21076016
11:58:20 737 8192000000 10426532480 10837155840 1214576704 12.911 100 93 179 1 1530483808 294219264 21687840
11:59:24 1248 8192000000 10449883952 10858795008 1222468912 12.985 100 96 189 1 1552453520 308755904 21228704
12:00:29 907 8192000000 10467290224 10881437696 1214848704 12.914 100 94 185 1 1573759584 337351968 21558912
12:01:33 1328 8192000000 10469585168 10905931776 1202577888 12.800 100 98 189 1 1571738752 346884688 22276176
12:02:39 905 8192000000 10501012800 10927964160 1199547664 12.772 100 97 184 0 1606946160 384804656 22593840
12:03:43 802 8192000000 10520741280 10949353472 1191377600 12.696 100 96 182 1 1633696496 420964864 21354032
12:04:45 1066 8192000000 10544411088 10992144384 1194214640 12.723 100 96 189 3 1648268368 431850896 22202832
12:05:49 2234 8192000000 10535410480 10969894912 1178270320 12.574 100 82 157 0 1638800624 441360144 19170160
12:07:06 4019 8192000000 10559058640 10999386112 1187196928 12.657 100 74 142 3 1656862224 451831792 17833504
12:08:18 7272 8192000000 10507714976 10969047040 1183287536 12.621 100 43 85 1 1611054688 416455952 11311200
12:09:53 29162 8192000000 10465385296 10974109696 1174980336 12.543 100 37 74 1 1614270800 429032464 10258000
12:11:29 17861 8192000000 10422332976 10901008384 1158371008 12.388 100 34 68 1 1522803792 355682992 8749792
12:12:48 17847 8192000000 10364432400 10835435520 1153174608 12.339 100 26 51 0 1467872672 308122208 6575856
12:14:31 50736 8192000000 10312974944 10795057152 1144703952 12.260 100 15 30 0 1392834672 243986448 4144272
12:16:22 37645 8192000000 10158131040 10658926592 1135870176 12.177 99 19 35 1 1258490272 118516480 4103616
12:18:01 50401 8192000000 10154066400 10593632256 1145408000 12.266 100 46 88 1 1258336240 103549168 9379072
12:19:56 10833 8192000000 10158860784 10648977408 1136401344 12.182 100 26 48 1 1282616464 140820240 5394880
12:21:13 41379 8192000000 10192006512 10636361728 1123679280 12.062 99 41 78 0 1269188768 137223312 8286176
12:23:00 88577 8192000000 10107253504 10605871104 1099926800 11.837 97 16 32 0 1184920384 81779248 3214336
12:26:14 182549 8192000000 10093682224 10537779200 1149493296 12.305 100 41 80 0 1202385792 44323040 8569456
12:30:26 69185 8192000000 10052146448 10526851072 1136614544 12.184 99 13 24 0 1168164144 29303232 2246368
12:32:47 25706 8192000000 10086063072 10524897280 1131135248 12.132 99 30 60 0 1166308768 29231328 5942192
12:34:27 89051 8192000000 10055838416 10554286080 1108176208 11.915 98 13 26 0 1185589536 74595824 2817504
12:36:56 63481 8192000000 10056684416 10513367040 1130224064 12.123 100 7 12 1 1160595328 29244656 1126608
12:39:09 79310 8192000000 10082418768 10538815488 1127422448 12.097 99 14 28 1 1162260320 31828000 3009872
12:41:47 41636 8192000000 10103763520 10555502592 1145376688 12.266 100 33 61 1 1216990800 64711680 6902432
12:43:52 46383 8192000000 10081497856 10535047168 1123327360 12.058 98 17 31 0 1163102096 36240272 3534464
12:45:42 91367 8192000000 10089443856 10558763008 1123183360 12.057 98 13 26 0 1191369056 65146752 3038944
12:48:16 106255 8192000000 10153716416 10630086656 1120241376 12.029 98 25 50 1 1224564144 98837568 5485200
12:51:07 11536 8192000000 10073609920 10512695296 1143877808 12.252 100 43 78 2 1177583120 24915456 8789856
12:52:28 42891 8192000000 10089733488 10530000896 1098924000 11.827 96 29 57 0 1146692464 42144288 5624176
12:54:12 47960 8192000000 10102363824 10553430016 1117999728 12.008 98 33 64 1 1195847520 71115696 6732096
12:56:03 10985 8192000000 10162170448 10595442688 1153012272 12.338 100 56 111 0 1264014528 99496720 11505536
12:57:16 74960 8192000000 10185522016 10690031616 1135430896 12.173 99 26 52 0 1264360784 123631440 5298448
12:59:31 56415 8192000000 10090011136 10521120768 1108161248 11.915 97 32 64 0 1155296688 40970048 6165392
13:01:30 36505 8192000000 10113886928 10581770240 1094049552 11.781 96 47 92 1 1180938912 77874416 9014944
13:03:12 64663 8192000000 10117625520 10598100992 1112315520 11.954 98 48 94 0 1201150864 79129952 9705392
13:05:23 68180 8192000000 10094760512 10546925568 1133255152 12.152 99 29 58 1 1179684000 40748688 5680160
13:07:34 72314 8192000000 10088003344 10540896256 1124971232 12.074 98 30 58 0 1181482640 50086160 6425248
13:09:52 1434 8192000000 10104505296 10554679296 1144923440 12.262 100 43 80 4 1206584832 53411728 8249664
13:10:55 47134 8192000000 10101988032 10549420032 1144699488 12.260 100 18 36 0 1203636160 54896448 4040224
13:12:42 62779 8192000000 10110277840 10566246400 1116052128 11.990 97 15 28 1 1192774864 73729296 2993440
13:14:45 86190 8192000000 10079832080 10608320512 1125779136 12.082 99 15 30 0 1216938560 87632272 3527152
13:17:22 95466 8192000000 10074277344 10551046144 1095626960 11.796 96 12 24 0 1184788160 86425408 2735792
13:19:58 36318 8192000000 10134800496 10574069760 1128380096 12.106 99 31 62 0 1217252016 82250928 6620992
13:21:49 36171 8192000000 10124529488 10565677056 1112229488 11.954 97 27 54 0 1214501904 96131200 6141216
13:23:38 82729 8192000000 10157307904 10624200704 1108673088 11.920 97 21 42 0 1253228240 140111136 4444016
13:26:01 66596 8192000000 10079749872 10530074624 1127863680 12.101 99 28 56 0 1190588416 57412464 5312272
13:28:11 32260 8192000000 10178041808 10620559360 1144766944 12.260 99 39 78 0 1259050848 106252016 8031888
13:29:47 69228 8192000000 10087378288 10612142080 1128781856 12.110 98 13 26 0 1205505040 74448368 2274816
13:32:06 81226 8192000000 10069242080 10528763904 1101497136 11.852 97 12 24 0 1159498896 55775456 2226304
13:34:28 62530 8192000000 10084429344 10546642944 1141270656 12.227 100 14 28 0 1193296352 48884816 3140880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          210055
MON$OLDEST_ACTIVE               210056
MON$OLDEST_SNAPSHOT             210056
MON$NEXT_TRANSACTION            210056
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                       2064736
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             753
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

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