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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15974744 kB
MemAvailable 31032348 kB
Buffers 0 kB
Cached 15709856 kB
SwapCached 2500 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-21 22:38:23
DTS_END                         2022-07-21 22:38:23.6880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54506
TRN_ID                          233109
ATT_ID                          664
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: 9653.01 22245 1158361
customer order: creation 170.23 369 20427
customer order: refuse 14.87 385 1784
order to supplier: creation 46.52 1258 5582
order to supplier: removal 3.38 114985 405
invoice (draft): creation 45.64 3627 5477
invoice (draft): removal 55.58 844 6670
invoice accept: apply 41.89 18880 5027
invoice accept: cancel 50.79 875 6095
customer reserve: creation 3963.23 182 475587
customer reserve: removal 4963.64 61 595637
realization accept: apply 83.03 201 9964
realization accept: cancel 82.82 113 9938
payment from customer: creation 60.30 117 7236
payment from customer: removal 5.90 80 708
payment to supplier: creation 49.77 153 5972
payment to supplier: removal 3.43 70 411
service: total inventory turnovers 4.59 1141 551
service: total monetary turnovers 6.63 37 795
service: aggregate perf. data 0.76 22462 91
service: refresh index statistics 0.03 279069 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-07-2022 22:39:28 to 21-07-2022 22:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4963
TEST_TIME 2 3958
TEST_TIME 3 4463
TEST_TIME 4 5551
TEST_TIME 5 5246
TEST_TIME 6 5643
TEST_TIME 7 5751
TEST_TIME 8 5910
TEST_TIME 9 5926
TEST_TIME 10 6161
TEST_TIME 11 6313
TEST_TIME 12 6520
TEST_TIME 13 6526
TEST_TIME 14 7064
TEST_TIME 15 7138
TEST_TIME 16 7223
TEST_TIME 17 7364
TEST_TIME 18 7639
TEST_TIME 19 7773
TEST_TIME 20 7987
TEST_TIME 21 7981
TEST_TIME 22 7900
TEST_TIME 23 8114
TEST_TIME 24 8355
TEST_TIME 25 9037
TEST_TIME 26 9304
TEST_TIME 27 9464
TEST_TIME 28 9417
TEST_TIME 29 9579
TEST_TIME 30 10733
TEST_TIME 31 10760
TEST_TIME 32 10762
TEST_TIME 33 10881
TEST_TIME 34 10830
TEST_TIME 35 10707
TEST_TIME 36 10575
TEST_TIME 37 10639
TEST_TIME 38 10598
TEST_TIME 39 10389
TEST_TIME 40 10386
TEST_TIME 41 10500
TEST_TIME 42 10363
TEST_TIME 43 10567
TEST_TIME 44 10461
TEST_TIME 45 10350
TEST_TIME 46 10289
TEST_TIME 47 10154
TEST_TIME 48 10290
TEST_TIME 49 10309
TEST_TIME 50 10208
TEST_TIME 51 10097
TEST_TIME 52 10017
TEST_TIME 53 10075
TEST_TIME 54 9982
TEST_TIME 55 10039
TEST_TIME 56 9989
TEST_TIME 57 9884
TEST_TIME 58 9874
TEST_TIME 59 9835
TEST_TIME 60 9735
TEST_TIME 61 9713
TEST_TIME 62 9680
TEST_TIME 63 9698
TEST_TIME 64 9753
TEST_TIME 65 9740
TEST_TIME 66 9799
TEST_TIME 67 9752
TEST_TIME 68 9830
TEST_TIME 69 9895
TEST_TIME 70 9890
TEST_TIME 71 9868
TEST_TIME 72 10015
TEST_TIME 73 9997
TEST_TIME 74 10087
TEST_TIME 75 10083
TEST_TIME 76 9986
TEST_TIME 77 9961
TEST_TIME 78 9910
TEST_TIME 79 9903
TEST_TIME 80 9945
TEST_TIME 81 9928
TEST_TIME 82 9886
TEST_TIME 83 9853
TEST_TIME 84 9851
TEST_TIME 85 9781
TEST_TIME 86 9758
TEST_TIME 87 9719
TEST_TIME 88 9653
TEST_TIME 89 9644
TEST_TIME 90 9596
TEST_TIME 91 9708
TEST_TIME 92 9670
TEST_TIME 93 9702
TEST_TIME 94 9723
TEST_TIME 95 9753
TEST_TIME 96 9820
TEST_TIME 97 9771
TEST_TIME 98 9822
TEST_TIME 99 9829
TEST_TIME 100 9823
TEST_TIME 101 9894
TEST_TIME 102 9921
TEST_TIME 103 9893
TEST_TIME 104 9913
TEST_TIME 105 9854
TEST_TIME 106 9819
TEST_TIME 107 9785
TEST_TIME 108 9781
TEST_TIME 109 9760
TEST_TIME 110 9705
TEST_TIME 111 9695
TEST_TIME 112 9693
TEST_TIME 113 9713
TEST_TIME 114 9720
TEST_TIME 115 9766
TEST_TIME 116 9730
TEST_TIME 117 9726
TEST_TIME 118 9727
TEST_TIME 119 9709
TEST_TIME 120 9655

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-07-2022 22:39:31 to 21-07-2022 22:39:35.

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 1597370 1597370 0 0.00 0 43679 6 0 0
doc_list_biud 1597370 1597370 0 0.00 0 40631 29 0 0
sp_add_invoice_to_stock 5106 5027 79 1.54 27 178450 18881 0 79
sp_cancel_adding_invoice 6095 6095 0 0.00 4 55888 876 0 0
sp_cancel_client_order 1784 1784 0 0.00 4 14463 385 0 0
sp_cancel_customer_reserve 595637 595637 0 0.00 0 40632 61 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 2736 81 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 1453 71 0 0
sp_cancel_supplier_invoice 6674 6670 4 0.05 1 14159 845 0 4
sp_cancel_supplier_order 405 405 0 0.00 14 1080949 114985 0 0
sp_cancel_write_off 9938 9938 0 0.00 1 15307 114 0 0
sp_client_order 20427 20427 0 0.00 3 33300 370 0 0
sp_customer_reserve 475587 475587 0 0.00 0 50054 182 0 0
sp_fill_shopping_cart 31474 31474 0 0.00 1 9653 40 0 0
sp_fill_shopping_cart_clo_res 475582 475582 0 0.00 0 27280 14 0 0
sp_get_clo_for_invoice 5022 5022 0 0.00 0 32603 287 0 0
sp_kill_cost_storno 17152 17152 0 0.00 0 43667 68 0 0
sp_kill_qstorno_ret_qs2qd 1208287 1208287 0 0.00 0 40628 33 0 0
sp_kill_qty_storno 635515 635515 0 0.00 0 40630 71 0 0
sp_lock_selected_doc 20793 20793 0 0.00 0 8731 9 0 0
sp_make_cost_storno 28194 28194 0 0.00 0 23870 63 0 0
sp_multiply_rows_for_pdistr 28194 28194 0 0.00 0 18311 29 0 0
sp_multiply_rows_for_qdistr 20427 20427 0 0.00 0 22222 154 0 0
sp_pay_from_customer 7236 7236 0 0.00 4 18334 117 0 0
sp_pay_to_supplier 5972 5972 0 0.00 4 19998 154 0 0
sp_payment_common 13208 13208 0 0.00 1 16575 34 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 14451 354 0 0
sp_qd_handle_on_invoice_upd_sts 11117 11117 0 0.00 0 19046 342 0 0
sp_qd_handle_on_reserve_upd_sts 9964 9964 0 0.00 0 14816 63 0 0
sp_reserve_write_off 9964 9964 0 0.00 6 18335 201 0 0
sp_supplier_invoice 5477 5477 0 0.00 6 97107 3627 0 0
sp_supplier_order 5582 5582 0 0.00 5 41800 1258 0 0
srv_aggregate_perf_data 91 91 0 0.00 2478 304603 22463 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1132 128929 40017 0 0
srv_find_qd_qs_mism 1109285 1109285 0 0.00 0 12670 5 0 0
srv_make_invnt_saldo 551 551 0 0.00 25 35683 1141 0 0
srv_make_money_saldo 795 795 0 0.00 0 6651 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 204473 317316 279069 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5028 5028 0 0.00 0 11002 191 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 4278 114 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 4728 16 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 4941 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 2052 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 2203 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 11126 50 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 17745 1689 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 2582 10 0 0
t$perf-norm:sp_client_order 20427 20427 0 0.00 0 9206 19 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 9031 21 0 0
t$perf-norm:sp_pay_from_customer 7236 7236 0 0.00 0 7896 12 0 0
t$perf-norm:sp_pay_to_supplier 5972 5972 0 0.00 0 8409 11 0 0
t$perf-norm:sp_reserve_write_off 9964 9964 0 0.00 0 8552 11 0 0
t$perf-norm:sp_supplier_invoice 5477 5477 0 0.00 0 6230 23 0 0
t$perf-norm:sp_supplier_order 5582 5582 0 0.00 0 3408 13 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 1465 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 391 7 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 2256 39 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 1719 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10202 10044 158 1.54 0 6010 18 0 158
v_all_customers 37938 37938 0 0.00 0 20 1 0 0
v_all_suppliers 31118 31118 0 0.00 0 43 1 0 0
v_all_wares 40854 40854 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 3003 13 0 0
v_cancel_client_order 3568 3568 0 0.00 0 4024 5 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 2721 5 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 5321 9 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 798 5 0 0
v_cancel_supplier_invoice 734 726 8 1.08 0 5449 21 0 8
v_cancel_supplier_order 810 810 0 0.00 0 3581 12 0 0
v_cancel_write_off 2002 2002 0 0.00 0 4877 19 0 0
v_max_id_clo_ord 5577 5577 0 0.00 0 256 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 4512 23 0 0
v_max_id_ord_sup 5470 5470 0 0.00 0 7147 7 0 0
v_max_non_paid_invoice 1460 1460 0 0.00 0 1099 4 0 0
v_max_non_paid_realizn 3624 3624 0 0.00 0 3317 5 0 0
v_min_id_clo_ord 5577 5577 0 0.00 0 9167 12 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 1190 5 0 0
v_min_id_ord_sup 5470 5470 0 0.00 0 5193 7 0 0
v_min_non_paid_invoice 5972 5972 0 0.00 0 9004 26 0 0
v_min_non_paid_realizn 7236 7236 0 0.00 0 16573 27 0 0
v_our_firm 10140 10140 0 0.00 0 26 1 0 0
v_reserve_write_off 19928 19928 0 0.00 0 4555 7 0 0
x_lock_depdocs_on_canc_invoice 12402 12402 0 0.00 0 15157 132 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 25997 700 0 0
x_make_qstorno_1000_1200 5582 5582 0 0.00 1 41793 1170 0 0
x_make_qstorno_1200_2000 5477 5477 0 0.00 3 95449 3488 0 0
x_make_qstorno_2100_3300 475587 475587 0 0.00 0 50052 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-07-2022 22:39:36 to 21-07-2022 22:39:40.

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
20:08:27 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:28 30101 8192000000 9887106848 10194616320 967745216 10.565 100 88 176 0 997228256 5786976 23696064
20:11:04 19932 8192000000 9939767712 10261061632 1020337120 11.075 100 84 168 0 1050300080 7323152 22639808
20:12:26 11389 8192000000 9967478832 10297868288 1056685552 11.425 100 82 164 0 1085866880 8121744 21059584
20:13:41 13662 8192000000 10025909392 10375086080 1116194032 11.991 100 75 148 0 1143218448 9231888 17792528
20:15:03 6577 8192000000 10083678016 10445221888 1176606976 12.559 100 70 139 0 1202304064 10355264 15341824
20:16:15 3427 8192000000 10123961424 10488659968 1212918240 12.896 100 78 154 1 1242048880 12262352 16868288
20:17:22 3468 8192000000 10164077312 10533965824 1242911232 13.173 100 90 180 2 1277633904 14997888 19724784
20:18:30 1423 8192000000 10175836032 10551263232 1262541248 13.353 100 87 174 0 1295920576 14801488 18577840
20:19:33 1330 8192000000 10206731792 10587668480 1291435296 13.617 100 84 166 1 1323221616 13512608 18273712
20:20:37 11582 8192000000 10219632096 10612551680 1307668992 13.765 100 71 142 1 1336377920 13484640 15224288
20:21:49 7337 8192000000 10212015424 10615402496 1314874176 13.830 100 31 66 0 1327848640 5759776 7214688
20:22:57 9964 8192000000 10207764976 10623651840 1322476352 13.899 100 13 26 1 1330578752 5516592 2585808
20:24:13 6890 8192000000 10216197008 10635190272 1329800928 13.965 100 22 43 0 1340675504 6026816 4847760
20:25:20 58696 8192000000 10220350864 10645331968 1334334736 14.006 100 47 91 2 1351153120 5943376 10875008
20:27:23 4197 8192000000 10240048608 10658713600 1340826096 14.065 100 35 71 0 1355663344 6897760 7939488
20:28:29 8800 8192000000 10216807392 10637250560 1308231696 13.770 98 16 31 0 1316860960 5301360 3327904
20:29:38 10787 8192000000 10182607376 10595749888 1268359168 13.407 97 10 19 0 1275454784 5173120 1922496
20:30:53 36688 8192000000 10149963936 10559422464 1149060096 12.301 89 26 50 0 1159097712 4463120 5574496
20:32:30 8004 8192000000 10157464448 10564968448 1251227952 13.250 98 20 37 2 1260920224 5511968 4180304
20:33:38 4467 8192000000 10153600192 10550657024 1255469104 13.288 100 37 74 1 1270339600 6054208 8816288
20:34:43 3962 8192000000 10167299552 10575491072 1270243440 13.424 99 36 67 2 1283541856 5623472 7674944
20:35:49 26815 8192000000 10177553968 10597117952 1292837824 13.630 100 46 93 1 1309620768 5299936 11483008
20:37:16 7965 8192000000 10207008496 10617421824 1309616912 13.783 100 34 68 0 1323044112 5074432 8352768
20:38:29 21166 8192000000 10240903328 10649288704 1333598160 14.000 100 28 55 2 1344871328 4609248 6663920
20:39:55 2591 8192000000 10268510368 10679738368 1356997168 14.210 100 67 127 0 1380505168 9122400 14385600
20:40:59 42197 8192000000 10277700848 10693758976 1368192928 14.311 100 61 122 0 1394076256 12062768 13820560
20:42:48 5708 8192000000 10272836064 10700390400 1374490800 14.367 100 31 60 0 1390111632 9278976 6341856
20:44:03 28551 8192000000 10289337344 10716594176 1383729664 14.450 100 34 67 0 1404275088 11993776 8551648
20:45:44 8675 8192000000 10315517696 10741817344 1404020560 14.631 100 46 92 0 1429243008 14244192 10978256
20:47:08 5512 8192000000 10338600576 10763395072 1413069200 14.711 100 57 115 0 1447273872 20839792 13364880
20:48:18 4829 8192000000 10345346304 10771587072 1419841104 14.771 100 56 112 0 1455582672 21879872 13861696
20:49:26 4732 8192000000 10348678896 10774110208 1422567168 14.795 100 63 126 3 1458857904 20808480 15482256
20:50:49 21970 8192000000 10353449600 10778697728 1410853008 14.692 99 59 115 2 1451113488 26795008 13465472
20:52:14 2426 8192000000 10385291040 10800988160 1429746528 14.859 100 86 168 0 1487899984 37764160 20389296
20:53:22 6011 8192000000 10370513888 10793488384 1415658736 14.734 99 60 120 0 1468493072 38343040 14491296
20:54:35 4993 8192000000 10370553712 10797436928 1423494992 14.804 100 69 138 0 1481009088 39981824 17532272
20:55:49 4770 8192000000 10380604288 10799423488 1418701392 14.761 100 66 130 2 1479511744 44607024 16203328
20:56:59 9356 8192000000 10358572320 10786484224 1407675632 14.663 100 50 100 0 1466506272 45655440 13175200
20:58:22 11817 8192000000 10347249392 10780774400 1403622720 14.627 100 46 92 1 1457495552 41674736 12198096
20:59:46 3339 8192000000 10355494464 10766553088 1380872256 14.424 99 79 158 1 1446344912 44341136 21131520
21:00:54 1129 8192000000 10379171360 10784657408 1397438032 14.572 100 93 185 0 1477834480 56232368 24164080
21:02:03 4245 8192000000 10378142448 10795667456 1403241744 14.624 100 56 110 0 1478706192 59700496 15763952
21:03:10 14678 8192000000 10353204624 10796699648 1401185776 14.606 100 33 66 0 1464917888 53714864 10017248
21:04:26 30614 8192000000 10343557664 10789941248 1402650832 14.619 100 31 62 0 1458561856 46675312 9235712
21:06:23 18600 8192000000 10321801920 10760744960 1372590624 14.350 99 34 66 1 1424897472 42825104 9481744
21:08:10 27003 8192000000 10305098752 10764226560 1361967904 14.255 100 12 24 0 1412263520 46635152 3660464
21:09:39 37697 8192000000 10282375904 10734559232 1337560640 14.035 97 28 56 1 1355895072 11687120 6647312
21:11:18 107612 8192000000 10264797824 10702909440 1247627248 13.216 91 7 14 0 1260537232 11626448 1283536
21:14:06 5992 8192000000 10292266000 10723000320 1362546624 14.260 100 38 75 0 1383535344 12371248 8617472
21:15:30 56797 8192000000 10280484000 10717753344 1340790096 14.065 98 25 48 1 1361842400 14791232 6261072
21:17:33 128929 8192000000 10282645120 10743533568 1358114736 14.220 99 42 82 0 1385326400 16943776 10267888
21:20:45 59551 8192000000 10288548112 10749587456 1374605264 14.368 100 16 32 0 1401687824 23349024 3733536
21:24:33 40038 8192000000 10292896416 10742001664 1359885312 14.236 98 26 50 0 1378434832 12491808 6057712
21:26:20 25627 8192000000 10283056640 10750607360 1380770080 14.423 100 27 54 0 1403537488 16841808 5925600
21:27:47 25041 8192000000 10292501072 10751225856 1393339552 14.536 100 15 30 0 1405836640 9101280 3395808
21:29:21 18834 8192000000 10294577232 10750631936 1381866144 14.433 99 21 38 4 1399427008 12345088 5215776
21:31:21 30772 8192000000 10306663664 10756644864 1393115312 14.534 100 29 58 4 1410077472 10890544 6071616
21:32:52 84868 8192000000 10284915424 10742927360 1377951712 14.398 99 17 35 0 1393704176 11802880 3949584
21:35:22 37941 8192000000 10312781200 10760224768 1395384656 14.554 100 48 95 0 1414809776 8972128 10452992
21:37:02 36636 8192000000 10287935504 10764812288 1384676128 14.458 99 7 14 0 1399051280 12634016 1741136
21:38:39 69257 8192000000 10291978176 10758529024 1376470864 14.385 98 10 18 1 1386625552 8413488 1741200
21:40:49 48534 8192000000 10301685424 10767097856 1365404544 14.286 97 24 49 0 1382049056 11473632 5170880
21:42:39 47168 8192000000 10282292672 10743734272 1347414224 14.124 97 10 20 0 1360580928 10409872 2756832
21:44:30 41202 8192000000 10281896992 10741235712 1355915920 14.201 98 16 33 1 1369897776 10112064 3869792
21:46:14 60027 8192000000 10289742208 10747351040 1332583568 13.990 96 9 18 0 1352871808 18209152 2079088
21:48:14 20350 8192000000 10284212544 10731659264 1352867232 14.173 98 24 46 1 1368297296 9611072 5818992
21:49:36 35986 8192000000 10305923536 10763804672 1384527616 14.457 100 34 68 0 1412777360 20809648 7440096
21:51:46 50657 8192000000 10304656160 10767310848 1375576320 14.377 99 9 17 1 1392978112 15670432 1731360
21:53:48 94108 8192000000 10306041264 10748276736 1364346160 14.276 98 30 60 0 1383460288 12271024 6843104
21:56:30 73412 8192000000 10308670016 10747392000 1371971968 14.345 99 23 47 0 1389218272 12509984 4736320
21:58:45 49243 8192000000 10276097888 10754158592 1379750064 14.414 100 14 27 0 1400509520 17503120 3256336
22:00:37 63097 8192000000 10271932336 10733535232 1364163888 14.275 99 12 24 0 1374334800 7590720 2580192
22:02:41 27251 8192000000 10260928128 10711965696 1327386640 13.944 97 21 40 1 1338941312 6722592 4832080
22:04:11 43550 8192000000 10254973968 10712465408 1345515376 14.107 99 19 38 0 1359930896 9945168 4470352
22:06:03 36311 8192000000 10233791216 10678218752 1312742320 13.811 97 17 34 0 1326595936 9811232 4042384
22:07:40 74012 8192000000 10246289152 10701230080 1309491808 13.781 97 12 24 0 1331484096 19148288 2844000
22:09:54 68943 8192000000 10281245200 10721951744 1337236144 14.032 98 19 38 0 1350467920 9025408 4206368
22:12:08 54363 8192000000 10282485696 10736373760 1346775392 14.118 98 16 32 0 1361698816 11401936 3521488
22:14:03 67116 8192000000 10293327632 10748022784 1380334032 14.420 100 23 46 0 1398154224 13244176 4576016
22:16:20 86457 8192000000 10285364400 10744000512 1357291392 14.213 98 23 46 0 1377422128 15123504 5007232
22:18:57 42756 8192000000 10302986624 10759729152 1387298544 14.482 100 7 14 0 1401653568 13026496 1328528
22:20:41 38607 8192000000 10309650512 10758787072 1386538672 14.475 99 29 58 0 1404961888 11332896 7090320
22:22:21 50960 8192000000 10284759360 10739265536 1354939392 14.192 98 30 60 0 1378004448 16530464 6534592
22:24:17 101333 8192000000 10279131200 10738102272 1346342576 14.115 97 29 58 0 1368054672 14808800 6903296
22:27:05 60745 8192000000 10281611712 10740899840 1369566656 14.323 99 21 42 0 1387478768 13115488 4796624
22:29:10 80303 8192000000 10286907136 10753728512 1371304272 14.339 99 19 38 0 1393656096 16932672 5419152
22:31:50 85858 8192000000 10256010416 10703048704 1290978128 13.613 94 17 34 0 1308521840 13088176 4455536
22:34:16 43484 8192000000 10269866176 10718244864 1329998848 13.967 97 30 60 0 1344791536 7540384 7252304
22:36:07 63580 8192000000 10276540208 10727374848 1339901072 14.057 98 33 66 0 1356364352 9261040 7202240
22:38:14 21192 8192000000 9089592256 9316577280 250644416 2.968 19 5 10 0 258596912 6957088 995408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-07-2022 22:39:42 to 21-07-2022 22:39:57.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-07-2022 22:39:58 to 21-07-2022 22:40:00.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233108
MON$OLDEST_ACTIVE               236023
MON$OLDEST_SNAPSHOT             236023
MON$NEXT_TRANSACTION            236023
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-03 16:41:55.3470
MON$PAGES                       2036448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:316: 
:317: mscs-test-161.budzdorov.ru    Thu Jul 21 22:38:40 2022
:318:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:319: 
--- end of diff output ---

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

21.07.22 22:40:01. There are 3 logs in /var/tmp/logs-oltp30 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-21 22:40:03 No severe PSQL-related problems occured
21.07.22 22:40:04. End of report.