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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16540172 kB
MemAvailable 31524260 kB
Buffers 0 kB
Cached 15148052 kB
SwapCached 2740 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-21 10:37:17
DTS_END                         2022-04-21 10:37:17.3700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57348
TRN_ID                          210894
ATT_ID                          604
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: 8669.88 31585 1040386
customer order: creation 153.15 477 18378
customer order: refuse 12.95 466 1554
order to supplier: creation 41.80 1447 5016
order to supplier: removal 3.34 179739 401
invoice (draft): creation 41.52 3465 4982
invoice (draft): removal 50.58 1058 6069
invoice accept: apply 37.93 28737 4552
invoice accept: cancel 45.99 1150 5519
customer reserve: creation 3522.98 274 422758
customer reserve: removal 4485.73 103 538288
realization accept: apply 75.69 283 9083
realization accept: cancel 78.53 194 9423
payment from customer: creation 54.18 168 6501
payment from customer: removal 5.11 120 613
payment to supplier: creation 45.52 201 5462
payment to supplier: removal 3.03 116 364
service: total inventory turnovers 3.58 4401 430
service: total monetary turnovers 7.33 366 879
service: aggregate perf. data 0.92 15819 110
service: refresh index statistics 0.03 393107 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-04-2022 10:38:39 to 21-04-2022 10:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9250
TEST_TIME 2 8061
TEST_TIME 3 7173
TEST_TIME 4 6393
TEST_TIME 5 5889
TEST_TIME 6 5894
TEST_TIME 7 5629
TEST_TIME 8 6260
TEST_TIME 9 6063
TEST_TIME 10 6073
TEST_TIME 11 6002
TEST_TIME 12 5997
TEST_TIME 13 6075
TEST_TIME 14 6240
TEST_TIME 15 6227
TEST_TIME 16 6186
TEST_TIME 17 6251
TEST_TIME 18 6437
TEST_TIME 19 6654
TEST_TIME 20 6636
TEST_TIME 21 6840
TEST_TIME 22 7166
TEST_TIME 23 7437
TEST_TIME 24 7725
TEST_TIME 25 7788
TEST_TIME 26 7990
TEST_TIME 27 8005
TEST_TIME 28 8114
TEST_TIME 29 8272
TEST_TIME 30 8824
TEST_TIME 31 9030
TEST_TIME 32 9227
TEST_TIME 33 9886
TEST_TIME 34 10314
TEST_TIME 35 10347
TEST_TIME 36 10405
TEST_TIME 37 10260
TEST_TIME 38 10101
TEST_TIME 39 9970
TEST_TIME 40 9995
TEST_TIME 41 9814
TEST_TIME 42 9798
TEST_TIME 43 9674
TEST_TIME 44 9697
TEST_TIME 45 9715
TEST_TIME 46 9715
TEST_TIME 47 9701
TEST_TIME 48 9644
TEST_TIME 49 9562
TEST_TIME 50 9413
TEST_TIME 51 9351
TEST_TIME 52 9251
TEST_TIME 53 9115
TEST_TIME 54 9189
TEST_TIME 55 9153
TEST_TIME 56 9135
TEST_TIME 57 9152
TEST_TIME 58 9101
TEST_TIME 59 9165
TEST_TIME 60 9201
TEST_TIME 61 9216
TEST_TIME 62 9253
TEST_TIME 63 9233
TEST_TIME 64 9262
TEST_TIME 65 9398
TEST_TIME 66 9413
TEST_TIME 67 9452
TEST_TIME 68 9325
TEST_TIME 69 9291
TEST_TIME 70 9275
TEST_TIME 71 9206
TEST_TIME 72 9168
TEST_TIME 73 9074
TEST_TIME 74 9067
TEST_TIME 75 8994
TEST_TIME 76 9062
TEST_TIME 77 8994
TEST_TIME 78 8979
TEST_TIME 79 8976
TEST_TIME 80 8953
TEST_TIME 81 9003
TEST_TIME 82 9024
TEST_TIME 83 9027
TEST_TIME 84 9063
TEST_TIME 85 9082
TEST_TIME 86 9082
TEST_TIME 87 9068
TEST_TIME 88 9099
TEST_TIME 89 9028
TEST_TIME 90 8955
TEST_TIME 91 8978
TEST_TIME 92 8930
TEST_TIME 93 8877
TEST_TIME 94 8801
TEST_TIME 95 8773
TEST_TIME 96 8830
TEST_TIME 97 8827
TEST_TIME 98 8796
TEST_TIME 99 8781
TEST_TIME 100 8770
TEST_TIME 101 8828
TEST_TIME 102 8829
TEST_TIME 103 8937
TEST_TIME 104 8910
TEST_TIME 105 8902
TEST_TIME 106 8960
TEST_TIME 107 8930
TEST_TIME 108 8940
TEST_TIME 109 8959
TEST_TIME 110 8888
TEST_TIME 111 8857
TEST_TIME 112 8884
TEST_TIME 113 8869
TEST_TIME 114 8815
TEST_TIME 115 8777
TEST_TIME 116 8775
TEST_TIME 117 8744
TEST_TIME 118 8773
TEST_TIME 119 8758
TEST_TIME 120 8701

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-04-2022 10:38:42 to 21-04-2022 10:38:46.

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 1430833 1430833 0 0.00 0 20502 14 0 0
doc_list_biud 1430817 1430817 0 0.00 0 41175 45 0 0
sp_add_invoice_to_stock 4679 4552 127 2.71 40 239100 28738 0 127
sp_cancel_adding_invoice 5519 5519 0 0.00 6 76861 1150 0 0
sp_cancel_client_order 1554 1554 0 0.00 6 16510 467 0 0
sp_cancel_customer_reserve 538288 538288 0 0.00 1 41181 104 0 0
sp_cancel_pay_from_customer 613 613 0 0.00 4 5591 120 0 0
sp_cancel_pay_to_supplier 364 364 0 0.00 4 1554 117 0 0
sp_cancel_supplier_invoice 6079 6069 10 0.16 3 32296 1059 0 10
sp_cancel_supplier_order 401 401 0 0.00 34 1499981 179739 0 0
sp_cancel_write_off 9423 9423 0 0.00 2 21780 194 0 0
sp_client_order 18378 18378 0 0.00 6 28457 478 0 0
sp_customer_reserve 422758 422758 0 0.00 1 177811 275 0 0
sp_fill_shopping_cart 28364 28364 0 0.00 1 9748 78 0 0
sp_fill_shopping_cart_clo_res 422717 422717 0 0.00 0 23535 20 0 0
sp_get_clo_for_invoice 4498 4498 0 0.00 0 31964 337 0 0
sp_kill_cost_storno 15919 15919 0 0.00 0 7495 81 0 0
sp_kill_qstorno_ret_qs2qd 1092469 1092469 0 0.00 0 41075 46 0 0
sp_kill_qty_storno 574831 574831 0 0.00 0 41175 105 0 0
sp_lock_selected_doc 18851 18851 0 0.00 0 10990 9 0 0
sp_make_cost_storno 25544 25544 0 0.00 0 15795 81 0 0
sp_multiply_rows_for_pdistr 25540 25540 0 0.00 0 8170 39 0 0
sp_multiply_rows_for_qdistr 18376 18376 0 0.00 0 11763 179 0 0
sp_pay_from_customer 6501 6501 0 0.00 6 17939 168 0 0
sp_pay_to_supplier 5462 5462 0 0.00 7 16402 201 0 0
sp_payment_common 11963 11963 0 0.00 2 17927 43 0 0
sp_qd_handle_on_cancel_clo 1554 1554 0 0.00 0 16493 397 0 0
sp_qd_handle_on_invoice_upd_sts 10015 10015 0 0.00 0 22488 522 0 0
sp_qd_handle_on_reserve_upd_sts 9081 9081 0 0.00 0 14748 66 0 0
sp_reserve_write_off 9083 9083 0 0.00 8 21225 283 0 0
sp_supplier_invoice 4982 4982 0 0.00 10 83527 3466 0 0
sp_supplier_order 5016 5016 0 0.00 7 31312 1448 0 0
srv_aggregate_perf_data 110 110 0 0.00 2729 153117 15820 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 770 160030 39902 0 0
srv_find_qd_qs_mism 996375 996375 0 0.00 0 7888 11 0 0
srv_make_invnt_saldo 430 430 0 0.00 68 201225 4402 0 0
srv_make_money_saldo 879 879 0 0.00 1 10335 367 0 0
srv_recalc_idx_stat 4 4 0 0.00 282350 527505 393108 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4559 4559 0 0.00 1 19115 909 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 23682 473 0 0
t$perf-norm:sp_cancel_client_order 1554 1554 0 0.00 0 4338 31 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 4391 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 613 613 0 0.00 0 1598 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 364 364 0 0.00 0 9521 41 0 0
t$perf-norm:sp_cancel_supplier_invoice 347 347 0 0.00 0 3471 47 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 101665 7389 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 5779 22 0 0
t$perf-norm:sp_client_order 18379 18379 0 0.00 0 6886 37 0 0
t$perf-norm:sp_customer_reserve 884 884 0 0.00 0 1306 14 0 0
t$perf-norm:sp_pay_from_customer 6501 6501 0 0.00 0 12253 24 0 0
t$perf-norm:sp_pay_to_supplier 5462 5462 0 0.00 0 8058 24 0 0
t$perf-norm:sp_reserve_write_off 9083 9083 0 0.00 0 26465 29 0 0
t$perf-norm:sp_supplier_invoice 4982 4982 0 0.00 0 3881 32 0 0
t$perf-norm:sp_supplier_order 5017 5017 0 0.00 0 3679 31 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 567 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 41 4 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 2646 24 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 16270 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 157 62 0 0
v_add_invoice_to_stock 9244 8990 254 2.74 0 26157 31 0 254
v_all_customers 34218 34218 0 0.00 0 242 3 0 0
v_all_suppliers 28242 28242 0 0.00 0 246 4 0 0
v_all_wares 36740 36740 0 0.00 0 231 3 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 459 12 0 0
v_cancel_client_order 3108 3108 0 0.00 0 7707 13 0 0
v_cancel_customer_prepayment 1226 1226 0 0.00 0 1941 11 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 2719 14 0 0
v_cancel_payment_to_supplier 728 728 0 0.00 0 605 11 0 0
v_cancel_supplier_invoice 714 694 20 2.80 0 5643 32 0 20
v_cancel_supplier_order 802 802 0 0.00 0 6218 26 0 0
v_cancel_write_off 1888 1888 0 0.00 0 3947 28 0 0
v_max_id_clo_ord 5016 5016 0 0.00 0 1005 5 0 0
v_max_id_clo_res 884 884 0 0.00 0 1476 12 0 0
v_max_id_ord_sup 4977 4977 0 0.00 0 4985 8 0 0
v_max_non_paid_invoice 1334 1334 0 0.00 0 599 8 0 0
v_max_non_paid_realizn 3198 3198 0 0.00 0 761 7 0 0
v_min_id_clo_ord 5016 5016 0 0.00 0 9649 11 0 0
v_min_id_clo_res 884 884 0 0.00 0 5377 19 0 0
v_min_id_ord_sup 4977 4977 0 0.00 0 8117 9 0 0
v_min_non_paid_invoice 5462 5462 0 0.00 0 7865 18 0 0
v_min_non_paid_realizn 6501 6501 0 0.00 0 17919 15 0 0
v_our_firm 9128 9128 0 0.00 0 62 4 0 0
v_reserve_write_off 18162 18162 0 0.00 0 20431 14 0 0
x_lock_depdocs_on_canc_invoice 11241 11241 0 0.00 0 33485 149 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 20531 499 0 0
x_make_qstorno_1000_1200 5016 5016 0 0.00 3 31199 1287 0 0
x_make_qstorno_1200_2000 4982 4982 0 0.00 4 83392 3245 0 0
x_make_qstorno_2100_3300 422757 422757 0 0.00 0 177805 243 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-04-2022 10:38:47 to 21-04-2022 10:38:51.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:21 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 22632 8192000000 9722247040 10020442112 796695648 8.863 100 88 176 1 826188448 7578448 21914352
08:09:49 15999 8192000000 9749767712 10058874880 828389728 9.183 100 85 170 0 860933312 11773216 20770368
08:11:16 12365 8192000000 9793466256 10113757184 865015024 9.550 100 80 160 0 904540480 20849344 18676112
08:12:31 9835 8192000000 9849478240 10185003008 911646800 10.014 100 69 138 0 958800304 32097392 15056112
08:13:47 6847 8192000000 9916244128 10258059264 961430672 10.503 100 89 174 2 1028277584 47949200 18897712
08:14:57 3184 8192000000 9957872416 10313609216 991827392 10.799 100 90 174 3 1078277520 69182336 17267792
08:16:03 1869 8192000000 9994387440 10357047296 1011359744 10.989 100 83 162 0 1115559120 88374160 15825216
08:17:07 1117 8192000000 9986648624 10356502528 1034532544 11.212 100 97 188 0 1107828480 54021600 19274336
08:18:10 754 8192000000 10017183392 10394882048 1049403568 11.355 100 97 193 2 1136032304 66864736 19764000
08:19:11 1129 8192000000 10042761264 10417754112 1059691552 11.454 100 96 185 0 1160446816 81895248 18860016
08:20:15 1288 8192000000 10042098752 10423148544 1067506352 11.528 100 87 173 1 1163122992 78334400 17282240
08:21:17 7806 8192000000 10014620704 10392510464 1069345728 11.546 100 82 154 1 1133852448 50371904 14134816
08:22:25 2427 8192000000 10013226512 10405175296 1077198800 11.621 100 69 127 4 1141861824 51597760 13065264
08:23:28 1284 8192000000 10040560704 10424479744 1084681856 11.692 100 93 185 0 1167398400 63959920 18756624
08:24:31 1179 8192000000 10079866064 10470559744 1086708432 11.711 100 95 184 2 1203418016 97173648 19535936
08:25:32 2549 8192000000 10043406832 10433765376 1090305024 11.746 100 80 150 1 1168119920 62746784 15068112
08:26:36 1240 8192000000 10048297792 10435219456 1092065568 11.762 100 92 178 0 1173059232 62475792 18517872
08:27:38 1811 8192000000 10018066720 10406248448 1073729152 11.588 99 79 147 2 1138781664 50943984 14108528
08:28:41 868 8192000000 10024811920 10408988672 1077836608 11.627 100 86 168 0 1146533232 51093840 17602784
08:29:43 1240 8192000000 10035958640 10427604992 1074804160 11.598 100 86 169 1 1158419440 66488912 17126368
08:30:45 703 8192000000 10014084720 10398785536 1064848288 11.503 99 92 170 1 1136875776 53410240 18617248
08:31:46 1186 8192000000 10008583920 10382671872 1052156576 11.381 100 98 187 1 1131263152 58557504 20549072
08:32:50 975 8192000000 10011018928 10386690048 1043380576 11.297 100 87 171 0 1137795984 76074528 18340880
08:33:51 1100 8192000000 10012866400 10388451328 1051594560 11.376 100 86 167 0 1134427104 66147712 16684832
08:34:54 1479 8192000000 9972675984 10343628800 1025338064 11.124 99 79 155 0 1097497024 56294352 15864608
08:35:56 6072 8192000000 9998928800 10380808192 1047048768 11.332 100 54 102 2 1113872688 56029888 10794032
08:37:03 4021 8192000000 9975423488 10349850624 1021280304 11.084 98 69 127 2 1104980768 70962912 12737552
08:38:13 1480 8192000000 10040027488 10415788032 1051147408 11.372 99 93 183 1 1147406544 78772272 17486864
08:39:23 1377 8192000000 10039601296 10419412992 1067880976 11.532 100 88 167 3 1158141536 72244816 18015744
08:40:27 8154 8192000000 10029566496 10412834816 1077348336 11.622 100 73 136 1 1148240272 58093696 12798240
08:41:40 31687 8192000000 10081506304 10472992768 1091036192 11.753 100 70 138 0 1189632144 84249744 14346208
08:43:16 2168 8192000000 10101779600 10507272192 1100675904 11.844 100 66 123 1 1225418032 111803296 12938832
08:44:24 7804 8192000000 10167433648 10565132288 1104980624 11.885 99 71 138 0 1276513792 155781440 15751728
08:45:37 981 8192000000 10176264320 10577207296 1124208992 12.067 100 91 172 3 1292520016 149002192 19308832
08:46:41 2190 8192000000 10216738688 10608443392 1135516736 12.173 100 82 160 1 1330157184 177242736 17397712
08:47:51 1374 8192000000 10254887856 10647252992 1162156272 12.423 100 91 175 2 1365258720 184113520 18988928
08:48:57 1634 8192000000 10291587344 10691371008 1171959632 12.515 100 86 166 1 1406037584 215700912 18377040
08:50:02 756 8192000000 10317477184 10724790272 1171301760 12.509 99 94 183 0 1428207184 236173136 20732288
08:51:06 1054 8192000000 10375818672 10790387712 1189395632 12.678 100 94 186 2 1489004048 277252912 22355504
08:52:11 1338 8192000000 10424006640 10830368768 1196239808 12.741 100 90 179 0 1533291200 316931568 20119824
08:53:16 973 8192000000 10439497552 10845822976 1187855792 12.663 99 93 181 2 1546446368 337359280 21231296
08:54:22 1403 8192000000 10494057808 10917064704 1202720016 12.802 100 93 185 1 1601427024 376921776 21785232
08:55:26 858 8192000000 10498399456 10929184768 1207144688 12.843 100 95 185 0 1610690944 380695776 22850480
08:56:31 1913 8192000000 10524954992 10933383168 1186970464 12.655 100 87 170 1 1636286640 428290128 21026048
08:57:36 1593 8192000000 10565090928 10971660288 1189004912 12.674 100 88 176 0 1672216368 461710864 21500592
08:58:41 1052 8192000000 10571786976 11049328640 1192586592 12.707 100 96 186 1 1679331920 463268704 23476624
08:59:47 903 8192000000 10561576768 11003109376 1190834512 12.691 100 94 187 0 1664757712 451262624 22660576
09:00:51 9879 8192000000 10560885200 11022041088 1201759904 12.793 100 64 128 1 1664207056 446281536 16165616
09:02:17 18724 8192000000 10527076272 10979561472 1168394064 12.482 98 48 97 0 1616461584 435335584 12731936
09:03:39 9592 8192000000 10516872208 10960523264 1177663200 12.568 100 51 98 2 1619458096 429077360 12717536
09:04:59 14759 8192000000 10529035728 10974945280 1176821728 12.561 100 42 81 1 1628853824 441633920 10398176
09:06:16 15401 8192000000 10478187680 11017375744 1169701808 12.494 100 38 76 0 1625009200 445199616 10107776
09:07:33 36677 8192000000 10429209216 10927026176 1175709616 12.550 100 36 70 1 1547744368 363219008 8815744
09:09:12 40016 8192000000 10338366992 10830475264 1142143984 12.236 99 25 50 2 1443250352 294491824 6614544
09:10:54 3052 8192000000 10221829232 10685136896 1146196640 12.274 100 49 96 1 1330499888 173312080 10991168
09:11:58 48009 8192000000 10130744080 10605395968 1137516256 12.192 99 21 42 0 1232825008 90803920 4504832
09:14:21 109049 8192000000 10108277600 10567933952 1122171280 12.047 98 41 81 2 1203192528 72770112 8251136
09:17:18 114305 8192000000 10141978560 10586632192 1141360544 12.228 99 31 62 3 1225922864 78463808 6098512
09:20:18 47239 8192000000 10153639136 10583887872 1115867488 11.988 97 49 94 2 1217540976 91330240 10343248
09:22:08 22537 8192000000 10152146240 10596315136 1130160752 12.123 99 38 74 2 1231640928 92701552 8778624
09:23:34 36123 8192000000 10088180032 10590375936 1131527360 12.136 99 14 28 2 1216704304 82120976 3055968
09:25:12 160030 8192000000 10062410576 10531270656 1121724512 12.043 98 14 28 0 1159667968 35003264 2940192
09:29:38 60027 8192000000 10112345568 10578083840 1145227952 12.265 100 17 34 0 1206795568 57715152 3852464
09:31:54 53492 8192000000 10063595728 10516987904 1103540576 11.871 97 19 34 2 1147915552 40778736 3596240
09:33:56 58010 8192000000 10102395376 10565304320 1133318816 12.153 99 30 60 0 1200006496 60166480 6521200
09:35:55 73403 8192000000 10094582064 10592522240 1120473536 12.031 98 25 50 1 1216542848 90541920 5527392
09:38:16 26116 8192000000 10098829408 10542129152 1122835488 12.054 98 43 79 3 1163260192 32410256 8014448
09:39:51 17601 8192000000 10101853600 10526146560 1117621472 12.005 98 41 83 1 1175788016 49834832 8331712
09:41:16 52484 8192000000 10154425536 10625191936 1129884880 12.120 99 27 53 2 1225653408 90081328 5687200
09:43:12 65395 8192000000 10091441936 10569248768 1075168624 11.601 95 16 32 0 1172346752 93475648 3702480
09:45:21 88658 8192000000 10103088448 10573910016 1108389216 11.917 99 27 54 1 1172151376 57916512 5845648
09:47:51 888 8192000000 10060813920 10478796800 1127023168 12.093 100 93 170 8 1162039216 15897952 19118096
09:48:54 64913 8192000000 10105989024 10566332416 1135373776 12.172 100 23 46 0 1210695872 70587872 4734224
09:52:12 87485 8192000000 10082975808 10569367552 1116159616 11.991 97 21 38 1 1195066560 74812096 4094848
09:54:41 37228 8192000000 10115482352 10552868864 1114427728 11.974 97 39 76 1 1184620080 62116272 8076080
09:56:22 109443 8192000000 10080539120 10558029824 1126259600 12.086 98 32 64 0 1177353392 43738208 7355584
09:59:19 26898 8192000000 10144397232 10587639808 1121436176 12.041 99 60 120 0 1215103360 80960560 12706624
10:00:50 30404 8192000000 10122885072 10555899904 1120271872 12.030 98 45 86 0 1183247232 54558304 8417056
10:02:23 14892 8192000000 10092594544 10525315072 1111581488 11.947 99 53 100 1 1178177728 57630672 8965568
10:03:39 15851 8192000000 10099986032 10523344896 1127833344 12.101 100 40 78 1 1189243072 54000032 7409696
10:04:58 156652 8192000000 10071545168 10514477056 1118951328 12.017 99 31 62 0 1172567664 47515136 6101200
10:08:37 61137 8192000000 10045728704 10526150656 1120909136 12.036 99 21 42 0 1173882192 48841712 4131344
10:10:38 76742 8192000000 10087053376 10538668032 1129997680 12.121 99 7 14 0 1185177664 53718144 1461840
10:12:55 84852 8192000000 10057977680 10581626880 1100092272 11.839 97 16 32 0 1162267584 58867392 3307920
10:15:20 21456 8192000000 10081355120 10511949824 1135253056 12.171 100 38 73 1 1176102848 32417168 8432624
10:16:42 33188 8192000000 10128237168 10564456448 1111612704 11.948 97 34 66 0 1213010256 94012336 7385216
10:18:18 79887 8192000000 10143150800 10634489856 1122529728 12.051 99 19 38 0 1226817120 100157856 4129536
10:20:39 48443 8192000000 10051337888 10508656640 1087067072 11.715 97 17 34 1 1134393888 44065696 3261120
10:22:28 54161 8192000000 10093976752 10568515584 1106735024 11.901 98 37 72 3 1202819712 88454208 7630480
10:24:24 22351 8192000000 10084793760 10507902976 1102431584 11.861 98 47 94 1 1158886320 46963872 9490864
10:25:47 156675 8192000000 10114909840 10561748992 1116498208 11.994 99 32 64 0 1255777120 131952144 7326768
10:29:26 72096 8192000000 10082416448 10574856192 1128867712 12.111 100 10 20 0 1190616960 59532128 2217120
10:31:58 63871 8192000000 10085812848 10534543360 1122527664 12.051 99 17 34 0 1169793104 43857632 3407808
10:34:03 71121 8192000000 10066162688 10525970432 1086229952 11.707 96 22 44 0 1146792272 55690384 4871936
10:36:17 71154 8192000000 8980526096 9297440768 118937136 1.431 11 2 4 0 129402928 10152592 313200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-04-2022 10:38:53 to 21-04-2022 10:39:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-04-2022 10:39:09 to 21-04-2022 10:39:11.

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          211193
MON$OLDEST_ACTIVE               211194
MON$OLDEST_SNAPSHOT             211194
MON$NEXT_TRANSACTION            211194
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                       2043104
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             760
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 ---
:451: 
:452: mscs-test-161.budzdorov.ru    Thu Apr 21 10:37:31 2022
:453:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:454: 
--- end of diff output ---

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

21.04.22 10:39:12. 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-21 10:39:15 No severe PSQL-related problems occured
21.04.22 10:39:16. End of report.