Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.03.2021 10:30
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16982864 kB
MemAvailable 31530992 kB
Buffers 0 kB
Cached 14712612 kB
SwapCached 0 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.0
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 2021-03-28 10:26:57
DTS_END                         2021-03-28 10:26:56.9480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58854
TRN_ID                          228479
ATT_ID                          640
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: 8666.53 28805 1039983
customer order: creation 159.23 455 19108
customer order: refuse 13.98 389 1677
order to supplier: creation 42.81 1441 5137
order to supplier: removal 3.30 140486 396
invoice (draft): creation 43.72 4221 5246
invoice (draft): removal 49.40 1103 5928
invoice accept: apply 39.14 19644 4697
invoice accept: cancel 44.72 1177 5366
customer reserve: creation 3622.68 189 434721
customer reserve: removal 4366.70 82 524004
realization accept: apply 78.66 187 9439
realization accept: cancel 80.03 146 9604
payment from customer: creation 55.93 133 6711
payment from customer: removal 5.62 113 674
payment to supplier: creation 45.98 149 5517
payment to supplier: removal 3.30 105 396
service: total inventory turnovers 3.98 3427 478
service: total monetary turnovers 6.47 530 776
service: aggregate perf. data 0.87 14401 104
service: refresh index statistics 0.03 387728 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-03-2021 10:30:33 to 28-03-2021 10:30:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1011
TEST_TIME 2 2175
TEST_TIME 3 2485
TEST_TIME 4 2450
TEST_TIME 5 2882
TEST_TIME 6 3291
TEST_TIME 7 3426
TEST_TIME 8 3970
TEST_TIME 9 4049
TEST_TIME 10 4329
TEST_TIME 11 4554
TEST_TIME 12 4780
TEST_TIME 13 4922
TEST_TIME 14 5234
TEST_TIME 15 5713
TEST_TIME 16 6106
TEST_TIME 17 6369
TEST_TIME 18 6562
TEST_TIME 19 6651
TEST_TIME 20 6708
TEST_TIME 21 6886
TEST_TIME 22 6991
TEST_TIME 23 7189
TEST_TIME 24 7589
TEST_TIME 25 8042
TEST_TIME 26 8438
TEST_TIME 27 8524
TEST_TIME 28 8488
TEST_TIME 29 9127
TEST_TIME 30 10899
TEST_TIME 31 10648
TEST_TIME 32 10594
TEST_TIME 33 10629
TEST_TIME 34 10386
TEST_TIME 35 10183
TEST_TIME 36 10077
TEST_TIME 37 10054
TEST_TIME 38 9930
TEST_TIME 39 9937
TEST_TIME 40 9767
TEST_TIME 41 9611
TEST_TIME 42 9523
TEST_TIME 43 9346
TEST_TIME 44 9207
TEST_TIME 45 9271
TEST_TIME 46 9275
TEST_TIME 47 9146
TEST_TIME 48 9105
TEST_TIME 49 9091
TEST_TIME 50 9122
TEST_TIME 51 9075
TEST_TIME 52 9094
TEST_TIME 53 9064
TEST_TIME 54 9040
TEST_TIME 55 9139
TEST_TIME 56 9048
TEST_TIME 57 9052
TEST_TIME 58 9064
TEST_TIME 59 9187
TEST_TIME 60 9170
TEST_TIME 61 9100
TEST_TIME 62 8970
TEST_TIME 63 8967
TEST_TIME 64 8924
TEST_TIME 65 8880
TEST_TIME 66 8827
TEST_TIME 67 8758
TEST_TIME 68 8702
TEST_TIME 69 8736
TEST_TIME 70 8787
TEST_TIME 71 8756
TEST_TIME 72 8769
TEST_TIME 73 8744
TEST_TIME 74 8805
TEST_TIME 75 8821
TEST_TIME 76 8860
TEST_TIME 77 8828
TEST_TIME 78 8899
TEST_TIME 79 8911
TEST_TIME 80 8930
TEST_TIME 81 8918
TEST_TIME 82 9006
TEST_TIME 83 9060
TEST_TIME 84 8967
TEST_TIME 85 8870
TEST_TIME 86 8857
TEST_TIME 87 8821
TEST_TIME 88 8776
TEST_TIME 89 8746
TEST_TIME 90 8720
TEST_TIME 91 8687
TEST_TIME 92 8661
TEST_TIME 93 8628
TEST_TIME 94 8622
TEST_TIME 95 8632
TEST_TIME 96 8611
TEST_TIME 97 8727
TEST_TIME 98 8758
TEST_TIME 99 8796
TEST_TIME 100 8831
TEST_TIME 101 8817
TEST_TIME 102 8809
TEST_TIME 103 8750
TEST_TIME 104 8725
TEST_TIME 105 8685
TEST_TIME 106 8694
TEST_TIME 107 8683
TEST_TIME 108 8675
TEST_TIME 109 8669
TEST_TIME 110 8647
TEST_TIME 111 8648
TEST_TIME 112 8651
TEST_TIME 113 8645
TEST_TIME 114 8639
TEST_TIME 115 8633
TEST_TIME 116 8715
TEST_TIME 117 8727
TEST_TIME 118 8745
TEST_TIME 119 8723
TEST_TIME 120 8668
TEST_TIME 121 8597

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-03-2021 10:30:36 to 28-03-2021 10:30:40.

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 1441447 1441447 0 0.00 0 28487 8 0 0
doc_list_biud 1441446 1441446 0 0.00 0 109751 38 0 0
sp_add_invoice_to_stock 4826 4697 129 2.67 45 197571 19645 0 129
sp_cancel_adding_invoice 5366 5366 0 0.00 5 96971 1177 0 0
sp_cancel_client_order 1677 1677 0 0.00 4 15045 390 0 0
sp_cancel_customer_reserve 524004 524004 0 0.00 1 41011 83 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 2 11185 113 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 5941 106 0 0
sp_cancel_supplier_invoice 5938 5928 10 0.16 1 109755 1104 0 10
sp_cancel_supplier_order 396 396 0 0.00 6 1447152 140487 0 0
sp_cancel_write_off 9604 9604 0 0.00 1 22839 147 0 0
sp_client_order 19108 19108 0 0.00 4 29000 456 0 0
sp_customer_reserve 434721 434721 0 0.00 0 83374 190 0 0
sp_fill_shopping_cart 29475 29475 0 0.00 1 27551 54 0 0
sp_fill_shopping_cart_clo_res 434718 434718 0 0.00 0 24480 20 0 0
sp_get_clo_for_invoice 4694 4694 0 0.00 0 38607 449 0 0
sp_kill_cost_storno 16040 16040 0 0.00 0 22803 76 0 0
sp_kill_qstorno_ret_qs2qd 1063936 1063936 0 0.00 0 109750 44 0 0
sp_kill_qty_storno 561108 561108 0 0.00 0 109751 95 0 0
sp_lock_selected_doc 19685 19685 0 0.00 0 9069 6 0 0
sp_make_cost_storno 26361 26361 0 0.00 0 28471 74 0 0
sp_multiply_rows_for_pdistr 26360 26360 0 0.00 0 14617 32 0 0
sp_multiply_rows_for_qdistr 19108 19108 0 0.00 0 28987 273 0 0
sp_pay_from_customer 6711 6711 0 0.00 4 28323 133 0 0
sp_pay_to_supplier 5517 5517 0 0.00 5 24271 149 0 0
sp_payment_common 12227 12227 0 0.00 1 23463 28 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 14599 353 0 0
sp_qd_handle_on_invoice_upd_sts 10060 10060 0 0.00 0 33323 472 0 0
sp_qd_handle_on_reserve_upd_sts 9439 9439 0 0.00 0 13130 45 0 0
sp_reserve_write_off 9439 9439 0 0.00 6 23475 188 0 0
sp_supplier_invoice 5246 5246 0 0.00 6 129882 4222 0 0
sp_supplier_order 5137 5137 0 0.00 6 127844 1441 0 0
srv_aggregate_perf_data 104 104 0 0.00 2608 194210 14401 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1309 131179 48364 0 0
srv_find_qd_qs_mism 994636 994636 0 0.00 0 21933 6 0 0
srv_make_invnt_saldo 478 478 0 0.00 35 96374 3428 0 0
srv_make_money_saldo 776 776 0 0.00 1 9850 531 0 0
srv_recalc_idx_stat 4 4 0 0.00 146332 544935 387728 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 4697 4697 0 0.00 0 9697 212 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 4278 131 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 2760 18 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 638 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 660 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 763 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1600 27 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 25562 2033 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 4197 14 0 0
t$perf-norm:sp_client_order 19108 19108 0 0.00 0 9866 19 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 667 5 0 0
t$perf-norm:sp_pay_from_customer 6711 6711 0 0.00 0 3156 6 0 0
t$perf-norm:sp_pay_to_supplier 5517 5517 0 0.00 0 5963 11 0 0
t$perf-norm:sp_reserve_write_off 9439 9439 0 0.00 0 9720 13 0 0
t$perf-norm:sp_supplier_invoice 5246 5246 0 0.00 0 7538 23 0 0
t$perf-norm:sp_supplier_order 5137 5137 0 0.00 0 2195 18 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 452 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 232 5 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 3645 19 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 769 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 47 13 0 0
v_add_invoice_to_stock 9646 9388 258 2.67 0 6435 17 0 258
v_all_customers 34916 34916 0 0.00 0 86 1 0 0
v_all_suppliers 29106 29106 0 0.00 0 18 1 0 0
v_all_wares 38214 38214 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1342 8 0 0
v_cancel_client_order 3354 3354 0 0.00 0 4378 8 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 4437 12 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 1073 5 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 5934 19 0 0
v_cancel_supplier_invoice 784 764 20 2.55 0 1853 17 0 20
v_cancel_supplier_order 792 792 0 0.00 0 4441 21 0 0
v_cancel_write_off 1868 1868 0 0.00 0 6289 21 0 0
v_max_id_clo_ord 5136 5136 0 0.00 0 1384 2 0 0
v_max_id_clo_res 938 938 0 0.00 0 4527 17 0 0
v_max_id_ord_sup 5232 5232 0 0.00 0 5732 5 0 0
v_max_non_paid_invoice 1331 1331 0 0.00 0 74 2 0 0
v_max_non_paid_realizn 3532 3532 0 0.00 0 23420 10 0 0
v_min_id_clo_ord 5136 5136 0 0.00 0 27440 27 0 0
v_min_id_clo_res 938 938 0 0.00 0 4467 13 0 0
v_min_id_ord_sup 5232 5232 0 0.00 0 9814 9 0 0
v_min_non_paid_invoice 5516 5516 0 0.00 0 23449 18 0 0
v_min_non_paid_realizn 6711 6711 0 0.00 0 18918 18 0 0
v_our_firm 9656 9656 0 0.00 0 27 1 0 0
v_reserve_write_off 18874 18874 0 0.00 0 5570 6 0 0
x_lock_depdocs_on_canc_invoice 10912 10912 0 0.00 0 29747 147 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 18393 594 0 0
x_make_qstorno_1000_1200 5137 5137 0 0.00 1 127837 1327 0 0
x_make_qstorno_1200_2000 5246 5246 0 0.00 3 129871 4029 0 0
x_make_qstorno_2100_3300 434721 434721 0 0.00 0 82266 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-03-2021 10:30:41 to 28-03-2021 10:30:45.

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
07:57:00 3 8192000000 8799338064 8810844160 5349520 0.065 2 1 2 0 5447136 61840 35776
07:58:01 28280 8192000000 9735457376 10030551040 812125040 9.019 100 93 186 0 842276160 7770272 22380848
07:59:36 25736 8192000000 9766385696 10072440832 838359552 9.283 100 90 181 0 872737088 12823280 21554256
08:01:06 15950 8192000000 9799177776 10119782400 869440848 9.594 100 74 146 2 906800832 20487408 16872576
08:02:26 13795 8192000000 9871610416 10201751552 914523408 10.042 100 84 168 0 979448016 46452112 18472496
08:03:44 12717 8192000000 9921209968 10261045248 949869616 10.390 100 83 166 1 1030588000 62721824 17996560
08:04:59 5157 8192000000 9961605552 10314559488 996095504 10.841 100 81 156 2 1077622048 65844864 15681680
08:06:07 3216 8192000000 9995024976 10357645312 1017829952 11.051 100 81 162 0 1114735584 80265472 16640160
08:07:12 3068 8192000000 10014547104 10380754944 1036558528 11.232 100 79 158 0 1123003840 70861392 15583920
08:08:16 1644 8192000000 10062332464 10429927424 1060562320 11.462 100 88 176 0 1168096928 89890000 17644608
08:09:19 24759 8192000000 9979986368 10389106688 1065156800 11.506 100 34 66 1 1112040128 39580096 7303232
08:10:49 4715 8192000000 9990450448 10379689984 1077631008 11.625 100 25 51 0 1103241792 20460592 5150192
08:11:55 3653 8192000000 10004330480 10410422272 1084028784 11.686 100 40 78 3 1135374208 42444400 8901024
08:13:01 6655 8192000000 10000644656 10396319744 1089674624 11.740 100 41 80 4 1114102320 15028144 9399552
08:14:09 15489 8192000000 10016706720 10402926592 1091788480 11.760 100 59 119 1 1115647792 10001824 13857488
08:15:24 4314 8192000000 10009573856 10416119808 1094279104 11.783 100 32 64 0 1126875088 25810400 6785584
08:16:31 7932 8192000000 10020828656 10417967104 1099700112 11.835 100 45 89 0 1126408672 17247632 9460928
08:17:44 64287 8192000000 9974845088 10376409088 1026616944 11.136 94 30 58 0 1049609424 17338208 5654272
08:19:49 4654 8192000000 9950534624 10337509376 996080288 10.841 95 50 101 1 1032847632 26253168 10514176
08:20:56 6807 8192000000 9941568240 10322280448 1017858368 11.051 99 43 83 4 1051072016 23286912 9926736
08:22:04 8220 8192000000 9933914880 10313289728 1013913072 11.013 99 34 68 0 1035797840 14322768 7562000
08:23:20 18774 8192000000 9925473296 10319163392 1014511248 11.019 98 37 75 1 1033714160 11396032 7806880
08:24:40 3577 8192000000 9965323152 10358779904 1039957712 11.264 99 53 102 3 1077202880 24931168 12314000
08:25:45 5152 8192000000 9969804048 10366509056 1061129408 11.467 100 43 86 0 1086183728 15209504 9844816
08:26:52 12453 8192000000 9990995296 10379644928 1073717984 11.588 100 41 84 2 1097514096 14246400 9549712
08:28:29 57985 8192000000 10027484384 10442543104 1086574560 11.710 100 45 91 2 1139702096 42759360 10368176
08:30:28 86801 8192000000 10075017984 10478694400 1103437024 11.870 100 39 78 1 1171621760 59713984 8470752
08:33:04 28499 8192000000 10141469600 10554068992 1123835840 12.063 100 42 84 2 1242036752 108811824 9389088
08:34:41 13317 8192000000 10159171088 10571161600 1136506544 12.183 100 37 71 3 1271369872 126332784 8530544
08:36:03 11609 8192000000 10214992768 10637443072 1147855232 12.289 100 54 104 3 1318104352 158629040 11620080
08:37:20 13648 8192000000 10252836640 10670022656 1160030096 12.404 100 57 114 1 1357929664 184128384 13771184
08:38:43 2007 8192000000 10321597952 10721914880 1177417568 12.566 100 81 159 2 1419529728 224619872 17492288
08:39:56 1799 8192000000 10352107792 10757795840 1190997280 12.693 100 85 166 1 1451313488 241825024 18491184
08:41:03 2794 8192000000 10375726016 10788282368 1195775664 12.737 100 90 179 3 1481889984 265558992 20555328
08:42:12 2944 8192000000 10373386096 10776055808 1189467664 12.678 100 90 177 1 1465142512 255912544 19762304
08:43:24 1831 8192000000 10397897616 10801373184 1176041232 12.553 100 95 188 1 1496829472 299794080 20994160
08:44:31 2228 8192000000 10428011248 10835070976 1158423632 12.388 98 90 179 0 1518125072 339527136 20174304
08:45:39 1304 8192000000 10467832544 10874208256 1188792800 12.672 100 86 169 3 1564796384 355883680 20119904
08:46:43 8664 8192000000 10499465504 10904866816 1185897344 12.645 100 85 169 0 1599887376 393792080 20197952
08:47:58 1959 8192000000 10543129568 10955825152 1194916752 12.729 100 91 180 1 1637159552 420544064 21698736
08:49:05 3124 8192000000 10516204992 10942050304 1174228192 12.536 100 75 151 1 1618418768 425968688 18221888
08:50:13 4137 8192000000 10541033792 10992500736 1164036208 12.441 100 58 115 0 1641713632 462540848 15136576
08:51:20 3429 8192000000 10515863344 11000680448 1167106560 12.470 100 61 120 0 1610776384 427873232 15796592
08:52:27 11137 8192000000 10526745152 10989944832 1179061664 12.581 100 44 88 0 1632805072 442037808 11705600
08:53:54 18246 8192000000 10544498320 11003277312 1144578480 12.259 99 33 66 1 1631576064 477862960 9134624
08:55:16 26688 8192000000 10481055168 10978103296 1122356032 12.049 100 24 49 0 1594923680 465304880 7262768
08:56:45 13973 8192000000 10116943424 10628526080 1122125488 12.047 98 29 57 0 1188425328 60267952 6031888
08:58:00 27981 8192000000 10099875776 10544857088 1131928304 12.140 100 39 78 0 1199328832 57911104 9489424
08:59:33 79211 8192000000 10095214624 10588573696 1133092496 12.151 100 10 19 2 1215210752 79621472 2496784
09:01:55 72043 8192000000 10084178672 10537332736 1109859264 11.931 99 22 44 1 1166587584 52102784 4625536
09:04:07 39968 8192000000 10059431728 10507587584 1106368032 11.898 99 37 71 2 1155635488 42020032 7247424
09:05:57 60709 8192000000 10055437680 10496995328 1115380256 11.983 99 14 28 0 1147362032 28959680 3022096
09:08:11 117449 8192000000 10123053792 10600062976 1125006512 12.074 99 18 36 0 1220824720 91120896 4697312
09:11:57 73329 8192000000 10052482064 10544943104 1120484688 12.032 99 7 14 0 1202244352 80256752 1502912
09:14:11 101764 8192000000 10092598576 10567258112 1144480576 12.258 100 17 34 1 1192304848 44622592 3201680
09:17:03 46811 8192000000 10079538256 10542534656 1137335616 12.190 99 25 50 1 1175928736 33188272 5404848
09:18:51 95465 8192000000 10120549424 10587963392 1125408400 12.078 98 15 30 1 1177609808 49160848 3040560
09:21:27 66015 8192000000 10079068448 10522685440 1123609120 12.061 98 9 18 0 1157090496 31774576 1706800
09:23:42 73460 8192000000 10150428320 10623946752 1154216864 12.349 100 30 60 2 1268426544 108169952 6039728
09:26:01 45530 8192000000 10080997280 10539872256 1120688384 12.033 98 16 32 1 1158872624 34960720 3223520
09:28:01 117017 8192000000 10071071296 10550444032 1133066176 12.150 99 22 44 0 1212879760 75357680 4455904
09:31:03 114767 8192000000 10066591120 10539884544 1105369392 11.889 97 15 30 1 1162444544 53591696 3483456
09:33:58 58220 8192000000 10136841360 10608975872 1140100432 12.216 100 21 42 2 1248632368 103891616 4640320
09:35:57 53905 8192000000 10084415216 10577735680 1141542352 12.230 100 10 20 0 1200855376 57207216 2105808
09:37:58 52529 8192000000 10071934208 10534572032 1094741328 11.788 96 28 56 0 1148988176 47873056 6373792
09:39:52 27915 8192000000 10050736672 10514591744 1083212272 11.678 97 22 44 1 1144623920 56911248 4500400
09:41:24 46312 8192000000 10076149712 10524241920 1087017680 11.714 97 21 42 0 1143534176 51896848 4619648
09:43:21 65319 8192000000 10115117008 10600996864 1134324192 12.162 100 21 42 0 1201320624 62699936 4296496
09:45:33 104734 8192000000 10096939600 10533076992 1062695232 11.482 94 21 42 1 1116211600 49336256 4180112
09:48:19 86752 8192000000 10046094992 10525671424 1057971536 11.437 94 9 18 1 1105627280 45739184 1916560
09:50:47 131179 8192000000 10045185200 10526806016 1091389888 11.756 98 23 46 2 1140738464 44088256 5260320
09:54:08 48484 8192000000 10035054864 10478776320 1088290816 11.726 98 13 24 0 1110969168 20158272 2520080
09:56:04 45202 8192000000 10025328032 10488791040 1097072208 11.810 99 4 8 0 1120625168 22916768 636192
09:57:50 45782 8192000000 10051697328 10497839104 1087842944 11.722 97 33 66 2 1133416784 38221600 7352240
09:59:41 34207 8192000000 10107122976 10558746624 1113791792 11.968 99 45 90 3 1209465040 86166592 9506656
10:01:18 57502 8192000000 10137515632 10598440960 1109244640 11.925 99 34 66 3 1207860624 90920880 7695104
10:03:33 99427 8192000000 10090963424 10552553472 1098727248 11.826 98 22 44 3 1217162016 114142976 4291792
10:07:07 43227 8192000000 10089014720 10515042304 1102656848 11.863 98 31 62 0 1156309104 47570592 6081664
10:08:51 110404 8192000000 10099710720 10564292608 1126777344 12.091 100 28 56 0 1197877456 65594624 5505488
10:12:05 51842 8192000000 10074618224 10582007808 1135865376 12.177 100 9 18 0 1209390288 71816128 1708784
10:13:56 66574 8192000000 10068526080 10559094784 1112023392 11.952 98 22 43 1 1163689872 46916128 4750352
10:16:10 26185 8192000000 10066239296 10505830400 1118592880 12.014 99 28 56 1 1144185872 20144992 5448000
10:18:01 55511 8192000000 10079699600 10529652736 1124977952 12.074 99 21 41 1 1173693152 44294688 4420512
10:19:58 48562 8192000000 10074389040 10512510976 1100503152 11.842 97 33 67 1 1134664928 27236416 6925360
10:21:47 50638 8192000000 10112390896 10597928960 1117463200 12.003 98 28 56 0 1219740032 96385392 5891440
10:23:47 95426 8192000000 10058420608 10486857728 1009081472 10.966 89 23 45 0 1055317824 41583040 4653312
10:26:27 78869 8192000000 9170053184 9478680576 307236128 3.614 28 7 13 0 348272912 39569520 1467264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-03-2021 10:30:47 to 28-03-2021 10:31:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 28-03-2021 10:31:02 to 28-03-2021 10:31:04.

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          229021
MON$OLDEST_ACTIVE               229022
MON$OLDEST_SNAPSHOT             229022
MON$NEXT_TRANSACTION            229022
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                       2088288
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2393 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2393 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2393 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2393 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sun Mar 28 10:28:31 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

28.03.21 10:31:05. 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
2021-03-28 10:31:08 No severe PSQL-related problems occured
28.03.21 10:31:09. End of report.