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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15684072 kB
MemAvailable 30784944 kB
Buffers 0 kB
Cached 16000900 kB
SwapCached 2456 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.1
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-11-09 07:47:00
DTS_END                         2021-11-09 07:47:00.5640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50108
TRN_ID                          228627
ATT_ID                          638
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: 9181.03 19968 1101724
customer order: creation 171.56 402 20587
customer order: refuse 14.23 368 1707
order to supplier: creation 44.40 1551 5328
order to supplier: removal 3.21 138068 385
invoice (draft): creation 45.13 3880 5415
invoice (draft): removal 50.57 1051 6068
invoice accept: apply 41.78 20356 5013
invoice accept: cancel 46.19 997 5543
customer reserve: creation 3920.50 196 470460
customer reserve: removal 4557.97 75 546956
realization accept: apply 82.07 204 9848
realization accept: cancel 77.15 142 9258
payment from customer: creation 58.51 137 7021
payment from customer: removal 5.75 102 690
payment to supplier: creation 47.44 141 5693
payment to supplier: removal 3.69 89 443
service: total inventory turnovers 3.68 4522 441
service: total monetary turnovers 6.31 794 757
service: aggregate perf. data 0.88 16760 106
service: refresh index statistics 0.04 209515 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-11-2021 07:47:56 to 09-11-2021 07:47:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9931
TEST_TIME 2 9280
TEST_TIME 3 8192
TEST_TIME 4 7471
TEST_TIME 5 7195
TEST_TIME 6 6657
TEST_TIME 7 6432
TEST_TIME 8 6426
TEST_TIME 9 6379
TEST_TIME 10 6591
TEST_TIME 11 6339
TEST_TIME 12 6343
TEST_TIME 13 6246
TEST_TIME 14 6264
TEST_TIME 15 6416
TEST_TIME 16 6563
TEST_TIME 17 6868
TEST_TIME 18 7056
TEST_TIME 19 7111
TEST_TIME 20 7494
TEST_TIME 21 7934
TEST_TIME 22 8018
TEST_TIME 23 8170
TEST_TIME 24 8204
TEST_TIME 25 8129
TEST_TIME 26 8631
TEST_TIME 27 8796
TEST_TIME 28 8758
TEST_TIME 29 8800
TEST_TIME 30 8713
TEST_TIME 31 9194
TEST_TIME 32 9616
TEST_TIME 33 10045
TEST_TIME 34 10805
TEST_TIME 35 10668
TEST_TIME 36 10526
TEST_TIME 37 10451
TEST_TIME 38 10485
TEST_TIME 39 10323
TEST_TIME 40 10267
TEST_TIME 41 10217
TEST_TIME 42 10096
TEST_TIME 43 10072
TEST_TIME 44 9954
TEST_TIME 45 9849
TEST_TIME 46 9867
TEST_TIME 47 10066
TEST_TIME 48 10037
TEST_TIME 49 9964
TEST_TIME 50 9970
TEST_TIME 51 9931
TEST_TIME 52 10008
TEST_TIME 53 9854
TEST_TIME 54 9682
TEST_TIME 55 9674
TEST_TIME 56 9620
TEST_TIME 57 9519
TEST_TIME 58 9496
TEST_TIME 59 9411
TEST_TIME 60 9452
TEST_TIME 61 9553
TEST_TIME 62 9483
TEST_TIME 63 9438
TEST_TIME 64 9373
TEST_TIME 65 9287
TEST_TIME 66 9200
TEST_TIME 67 9197
TEST_TIME 68 9154
TEST_TIME 69 9119
TEST_TIME 70 9114
TEST_TIME 71 9174
TEST_TIME 72 9182
TEST_TIME 73 9288
TEST_TIME 74 9254
TEST_TIME 75 9333
TEST_TIME 76 9355
TEST_TIME 77 9340
TEST_TIME 78 9341
TEST_TIME 79 9392
TEST_TIME 80 9304
TEST_TIME 81 9234
TEST_TIME 82 9218
TEST_TIME 83 9224
TEST_TIME 84 9160
TEST_TIME 85 9113
TEST_TIME 86 9049
TEST_TIME 87 9078
TEST_TIME 88 9056
TEST_TIME 89 9036
TEST_TIME 90 9088
TEST_TIME 91 9078
TEST_TIME 92 9148
TEST_TIME 93 9121
TEST_TIME 94 9151
TEST_TIME 95 9220
TEST_TIME 96 9233
TEST_TIME 97 9225
TEST_TIME 98 9302
TEST_TIME 99 9282
TEST_TIME 100 9370
TEST_TIME 101 9371
TEST_TIME 102 9288
TEST_TIME 103 9247
TEST_TIME 104 9241
TEST_TIME 105 9234
TEST_TIME 106 9215
TEST_TIME 107 9197
TEST_TIME 108 9147
TEST_TIME 109 9108
TEST_TIME 110 9109
TEST_TIME 111 9142
TEST_TIME 112 9203
TEST_TIME 113 9174
TEST_TIME 114 9153
TEST_TIME 115 9197
TEST_TIME 116 9212
TEST_TIME 117 9219
TEST_TIME 118 9249
TEST_TIME 119 9264
TEST_TIME 120 9187

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-11-2021 07:47:59 to 09-11-2021 07:48:02.

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 1534445 1534445 0 0.00 0 34630 8 0 0
doc_list_biud 1534445 1534445 0 0.00 0 28102 34 0 0
sp_add_invoice_to_stock 5107 5013 94 1.84 44 236311 20356 0 94
sp_cancel_adding_invoice 5543 5543 0 0.00 4 79831 998 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 12088 368 0 0
sp_cancel_customer_reserve 546956 546956 0 0.00 1 26219 76 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 6992 102 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 2 3820 90 0 0
sp_cancel_supplier_invoice 6073 6068 5 0.08 2 26421 1052 0 5
sp_cancel_supplier_order 385 385 0 0.00 11 1334255 138069 0 0
sp_cancel_write_off 9258 9258 0 0.00 1 14679 143 0 0
sp_client_order 20587 20587 0 0.00 4 39550 402 0 0
sp_customer_reserve 470460 470460 0 0.00 0 132961 197 0 0
sp_fill_shopping_cart 31330 31330 0 0.00 1 20189 46 0 0
sp_fill_shopping_cart_clo_res 470450 470450 0 0.00 0 19305 16 0 0
sp_get_clo_for_invoice 5003 5003 0 0.00 0 18250 354 0 0
sp_kill_cost_storno 15934 15934 0 0.00 0 14665 78 0 0
sp_kill_qstorno_ret_qs2qd 1109623 1109623 0 0.00 0 28102 41 0 0
sp_kill_qty_storno 584768 584768 0 0.00 0 28102 88 0 0
sp_lock_selected_doc 20494 20494 0 0.00 0 11234 6 0 0
sp_make_cost_storno 27565 27565 0 0.00 0 34624 64 0 0
sp_multiply_rows_for_pdistr 27565 27565 0 0.00 0 16091 41 0 0
sp_multiply_rows_for_qdistr 20587 20587 0 0.00 0 37513 178 0 0
sp_pay_from_customer 7021 7021 0 0.00 4 25932 138 0 0
sp_pay_to_supplier 5693 5693 0 0.00 5 16911 141 0 0
sp_payment_common 12714 12714 0 0.00 2 10835 22 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 12080 341 0 0
sp_qd_handle_on_invoice_upd_sts 10546 10546 0 0.00 0 25583 430 0 0
sp_qd_handle_on_reserve_upd_sts 9848 9848 0 0.00 0 18587 63 0 0
sp_reserve_write_off 9848 9848 0 0.00 6 19080 204 0 0
sp_supplier_invoice 5415 5415 0 0.00 7 79510 3880 0 0
sp_supplier_order 5328 5328 0 0.00 6 66342 1552 0 0
srv_aggregate_perf_data 106 106 0 0.00 2750 140716 16761 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1860 166624 42531 0 0
srv_find_qd_qs_mism 1052687 1052687 0 0.00 0 15798 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 34 104277 4522 0 0
srv_make_money_saldo 757 757 0 0.00 1 17088 794 0 0
srv_recalc_idx_stat 5 5 0 0.00 105382 419733 209515 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5013 5013 0 0.00 0 13389 204 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 3727 119 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 2698 13 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 3542 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 5823 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 881 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 795 16 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 32897 2104 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 615 5 0 0
t$perf-norm:sp_client_order 20587 20587 0 0.00 0 17585 23 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 1438 7 0 0
t$perf-norm:sp_pay_from_customer 7021 7021 0 0.00 0 7801 11 0 0
t$perf-norm:sp_pay_to_supplier 5693 5693 0 0.00 0 6425 7 0 0
t$perf-norm:sp_reserve_write_off 9848 9848 0 0.00 0 8247 12 0 0
t$perf-norm:sp_supplier_invoice 5415 5415 0 0.00 0 4852 19 0 0
t$perf-norm:sp_supplier_order 5328 5328 0 0.00 0 4847 18 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 550 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 451 9 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 8335 33 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 3431 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10184 9996 188 1.84 0 7744 19 0 188
v_all_customers 37202 37202 0 0.00 0 52 1 0 0
v_all_suppliers 29830 29830 0 0.00 0 44 1 0 0
v_all_wares 41174 41174 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1547 10 0 0
v_cancel_client_order 3414 3414 0 0.00 0 3759 5 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1886 7 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2583 7 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 1876 6 0 0
v_cancel_supplier_invoice 778 768 10 1.28 0 5315 22 0 10
v_cancel_supplier_order 770 770 0 0.00 0 2870 11 0 0
v_cancel_write_off 1916 1916 0 0.00 0 824 14 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 732 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 2506 18 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 4972 6 0 0
v_max_non_paid_invoice 1521 1521 0 0.00 0 619 3 0 0
v_max_non_paid_realizn 3851 3851 0 0.00 0 2001 3 0 0
v_min_id_clo_ord 5328 5328 0 0.00 0 1899 5 0 0
v_min_id_clo_res 993 993 0 0.00 0 3970 11 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 4135 5 0 0
v_min_non_paid_invoice 5693 5693 0 0.00 0 8504 15 0 0
v_min_non_paid_realizn 7021 7021 0 0.00 0 10831 15 0 0
v_our_firm 10312 10312 0 0.00 0 28 1 0 0
v_reserve_write_off 19696 19696 0 0.00 0 3473 7 0 0
x_lock_depdocs_on_canc_invoice 11227 11227 0 0.00 0 16128 144 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 5282 441 0 0
x_make_qstorno_1000_1200 5328 5328 0 0.00 2 65937 1459 0 0
x_make_qstorno_1200_2000 5415 5415 0 0.00 2 79489 3711 0 0
x_make_qstorno_2100_3300 470460 470460 0 0.00 0 132960 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-11-2021 07:48:03 to 09-11-2021 07:48:07.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:17:04 8 8192000000 8806371888 8820748288 10406352 0.126 4 1 2 0 10657280 139600 111328
05:18:05 26231 8192000000 9716702672 10016075776 798048784 8.877 100 86 172 1 826876400 7410896 21416720
05:19:40 16643 8192000000 9762680192 10069585920 838145376 9.281 100 92 184 1 872769328 12204880 22419072
05:21:07 17053 8192000000 9803149936 10122690560 867939664 9.579 100 86 172 0 911342240 23705536 19697040
05:22:32 14274 8192000000 9880851040 10215006208 923219520 10.128 100 80 160 4 983974896 43369984 17385392
05:23:49 9560 8192000000 9905403920 10251857920 952926976 10.420 100 76 152 0 1018040064 49081872 16031216
05:25:02 5326 8192000000 9954424128 10314493952 993830384 10.819 100 73 145 0 1072710640 64238720 14641536
05:26:11 3907 8192000000 9986552832 10346184704 1024420176 11.115 100 88 176 3 1103758560 61020512 18317872
05:27:18 2497 8192000000 10033786320 10399563776 1050358560 11.364 100 88 174 1 1147817264 79108864 18349840
05:28:21 1133 8192000000 10053018768 10424299520 1065866960 11.513 100 92 183 0 1161765936 76981472 18917504
05:29:24 13857 8192000000 10032314944 10408251392 1072385600 11.575 100 71 142 2 1138505680 50797712 15322368
05:30:39 9208 8192000000 10024705824 10435112960 1079690208 11.645 100 34 69 0 1135744000 49361600 6692192
05:31:49 7217 8192000000 9988710224 10388398080 1081423776 11.661 100 44 89 3 1111909680 20805184 9680720
05:32:57 27112 8192000000 10003136352 10418192384 1083579200 11.682 100 50 100 1 1114622368 19814368 11228800
05:34:27 7638 8192000000 10016413408 10423508992 1092065552 11.762 100 36 72 1 1141827440 41960352 7801536
05:35:38 7296 8192000000 9998905792 10406375424 1086491536 11.709 99 30 57 0 1104881264 11689696 6700032
05:36:49 57742 8192000000 9985760912 10385137664 1067835392 11.531 98 34 69 0 1082500672 6733008 7932272
05:38:48 5678 8192000000 9990048704 10386960384 1066747984 11.521 99 51 102 0 1093226000 13993872 12484144
05:39:54 6044 8192000000 9967820944 10364678144 1032924592 11.197 99 55 110 5 1068512496 21748464 13839440
05:41:02 7694 8192000000 9957186576 10337153024 1020666784 11.078 99 32 63 0 1053653888 26450432 6536672
05:42:10 22052 8192000000 9963728128 10361425920 1020679440 11.079 99 28 56 0 1060088624 33708640 5700544
05:43:33 5489 8192000000 9962975328 10345660416 1012705840 11.002 98 50 99 1 1044526288 21340032 10480416
05:44:40 9238 8192000000 9972280192 10355007488 1055418944 11.413 100 26 53 0 1081533136 20595184 5519008
05:45:50 72994 8192000000 9977529664 10388447232 1068085104 11.534 100 31 62 1 1099507280 25066720 6355456
05:48:13 11642 8192000000 10062037776 10459832320 1099858304 11.836 100 58 116 0 1171247968 60225456 11164208
05:49:33 11282 8192000000 10078073280 10475274240 1118421072 12.012 100 63 124 0 1178862832 47774096 12667664
05:50:55 14624 8192000000 10075225456 10479824896 1129152464 12.113 100 60 122 0 1187331536 45011392 13167680
05:52:14 9144 8192000000 10129668512 10530336768 1139856784 12.214 100 62 122 1 1221150720 68677376 12616560
05:53:28 8734 8192000000 10104459984 10521796608 1145070464 12.263 100 33 66 0 1220771376 68312784 7388128
05:54:41 25805 8192000000 10135966160 10556878848 1153704448 12.344 100 55 110 0 1248762000 82513344 12544208
05:56:17 16870 8192000000 10189497840 10602971136 1171236144 12.508 100 67 129 2 1296067584 110962240 13869200
05:57:40 10381 8192000000 10220160480 10633535488 1172370944 12.519 99 53 105 0 1314768896 131353616 11044336
05:58:56 9270 8192000000 10242817920 10675212288 1187751776 12.662 100 49 98 1 1353142720 153832848 11558096
06:00:11 8242 8192000000 10278831712 10693464064 1192396784 12.706 100 64 127 1 1383333344 176985760 13950800
06:01:24 4026 8192000000 10276384848 10685194240 1186309232 12.649 100 66 132 1 1377928800 176343648 15275920
06:02:33 3303 8192000000 10306999744 10706911232 1165797104 12.458 99 84 167 3 1391000880 206496736 18707040
06:03:42 17401 8192000000 10331212560 10739585024 1168027440 12.478 99 71 142 1 1415623520 231215760 16380320
06:05:11 3550 8192000000 10361192240 10759282688 1171256080 12.509 100 88 175 1 1452790528 262542912 18991536
06:06:20 4279 8192000000 10372929024 10778750976 1169204192 12.489 100 75 151 0 1470287264 283721824 17361248
06:07:29 3582 8192000000 10387607264 10794602496 1159517776 12.399 100 78 156 0 1482434352 305078320 17838256
06:08:41 2626 8192000000 10383569344 10786816000 1179174320 12.582 100 86 171 0 1482212448 283310704 19727424
06:09:52 1849 8192000000 10428544416 10841522176 1184208976 12.629 100 91 182 0 1519535056 314415472 20910608
06:11:00 3002 8192000000 10454038176 10856706048 1178431408 12.576 100 86 173 0 1546383984 347598064 20354512
06:12:07 4262 8192000000 10462395664 10875310080 1166782048 12.467 100 80 158 0 1556840880 370972320 19086512
06:13:19 2676 8192000000 10492539568 10945372160 1179625328 12.587 100 83 166 1 1598427040 398791952 20009760
06:14:40 6123 8192000000 10445145392 10880917504 1186713248 12.653 100 46 92 0 1538662064 340489488 11459328
06:15:50 15702 8192000000 10503636768 10965004288 1176948880 12.562 100 36 72 0 1597083728 410261312 9873536
06:17:11 22398 8192000000 10549876128 11002335232 1155055136 12.357 99 29 58 0 1629293216 465680144 8557936
06:18:35 18594 8192000000 10453464640 11004137472 1149050864 12.301 100 28 56 0 1616528512 459735936 7741712
06:19:56 28669 8192000000 10266675792 10796662784 1123491840 12.060 99 21 40 2 1439431792 310927088 5012864
06:21:28 25342 8192000000 10145895824 10665947136 1123151264 12.057 99 19 38 0 1240652784 113420224 4081296
06:22:58 94472 8192000000 10111062176 10549415936 1108230864 11.916 97 25 50 1 1189905312 76393104 5281344
06:25:36 117478 8192000000 10103533472 10570223616 1126020832 12.084 99 10 21 1 1155652144 27843104 1788208
06:28:42 166603 8192000000 10109251600 10581893120 1096992432 11.809 96 25 49 0 1147421872 45453216 4976224
06:32:41 68123 8192000000 10130910496 10596167680 1101149024 11.849 97 14 28 0 1232446480 127848848 3448608
06:34:59 84632 8192000000 10076296176 10515345408 1108965616 11.923 98 12 21 1 1129583456 18785136 1832704
06:37:26 56578 8192000000 10082239328 10508435456 1125730528 12.081 99 27 53 0 1158081760 26615680 5735552
06:39:23 27128 8192000000 10113358496 10580791296 1136899744 12.186 100 28 56 1 1199471056 56598272 5973040
06:40:52 109632 8192000000 10096646368 10553511936 1130228640 12.124 99 27 54 0 1171828496 35177312 6422544
06:43:47 109910 8192000000 10136983168 10573434880 1119092240 12.018 98 28 56 0 1177806928 52950272 5764416
06:46:45 14898 8192000000 10153007568 10567188480 1136852048 12.186 100 66 133 3 1239377840 86948064 15577728
06:48:01 53297 8192000000 10119037712 10575847424 1125267952 12.077 98 38 76 0 1219966752 86096912 8601888
06:50:35 26052 8192000000 10037335136 10479296512 1105759840 11.892 97 10 20 0 1123026000 15255024 2011136
06:52:02 130449 8192000000 10077545248 10564300800 1131188416 12.133 99 17 34 0 1178958480 44140704 3629360
06:55:36 7965 8192000000 10035073632 10468716544 1106968848 11.904 98 49 97 1 1129107424 10855888 11282688
06:56:47 57388 8192000000 10139298096 10597191680 1120901984 12.036 98 21 42 0 1200449888 75043616 4504288
06:58:54 65836 8192000000 10133391792 10615963648 1131996384 12.140 100 19 38 0 1259720208 123564512 4159312
07:01:02 15448 8192000000 10068157376 10499158016 1127420768 12.097 100 33 65 0 1155467808 21418624 6628416
07:02:26 71358 8192000000 10064671712 10503319552 1078060064 11.629 96 15 30 0 1135550848 54309600 3181184
07:04:39 17116 8192000000 10064578928 10494767104 1104026464 11.876 99 38 74 1 1145198464 33520208 7651792
07:06:16 59825 8192000000 10049882560 10509148160 1048040352 11.342 93 28 56 3 1097170912 43661088 5469472
07:08:17 86060 8192000000 10054944160 10515542016 1088890336 11.732 98 21 42 0 1160674016 67133472 4650208
07:10:56 57860 8192000000 10045039312 10495500288 1114781216 11.978 100 18 35 1 1151730272 33131776 3817280
07:12:59 80620 8192000000 10099553776 10549813248 1094705216 11.787 98 15 30 0 1166107184 68139408 3262560
07:15:19 51002 8192000000 10060750624 10500612096 1083590992 11.682 97 14 29 1 1137166240 50825024 2750224
07:17:11 56020 8192000000 10078015920 10510925824 1095403616 11.794 98 26 50 1 1191229024 90047312 5778096
07:19:09 84462 8192000000 10063564672 10492481536 1071280144 11.564 95 21 42 1 1129978272 54343760 4354368
07:21:34 56721 8192000000 10096896464 10536603648 1104142496 11.877 98 23 44 0 1174294000 65970576 4180928
07:23:36 42638 8192000000 10086200816 10542792704 1125267216 12.077 99 28 56 1 1188195504 57401360 5526928
07:25:42 37624 8192000000 10097025104 10531274752 1123495072 12.060 99 18 36 0 1163851408 36710224 3646112
07:27:20 122633 8192000000 10070060032 10558386176 1114112688 11.971 98 13 26 0 1170527888 53720208 2694992
07:30:36 61101 8192000000 10065724944 10536808448 1112515728 11.956 98 16 32 1 1154686112 39026048 3144336
07:32:45 59605 8192000000 10096617904 10578337792 1131397808 12.135 100 13 26 1 1184016672 50022128 2596736
07:34:44 58323 8192000000 10153404288 10614239232 1150453888 12.314 100 14 26 2 1246351376 92872528 3024960
07:36:45 48402 8192000000 10183347648 10644504576 1156148960 12.367 100 18 36 0 1301572704 141069664 4354080
07:38:43 61645 8192000000 10088420336 10609938432 1139666816 12.212 99 15 30 1 1179719968 37150576 2902576
07:40:49 47876 8192000000 10119300272 10571677696 1147350976 12.285 99 20 40 0 1204369648 52592688 4425984
07:42:38 67087 8192000000 10113179936 10556125184 1116035456 11.990 97 19 38 0 1225622288 104828912 4757920
07:44:52 23771 8192000000 10124030816 10546597888 1135592192 12.174 99 59 118 1 1205950208 58471680 11886336
07:46:19 25365 8192000000 10120852416 10563174400 1142640448 12.240 100 39 78 0 1218342512 67788112 7913952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-11-2021 07:48:09 to 09-11-2021 07:48:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-11-2021 07:48:25 to 09-11-2021 07:48:27.

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          224260
MON$OLDEST_ACTIVE               228753
MON$OLDEST_SNAPSHOT             228753
MON$NEXT_TRANSACTION            228753
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                       2075424
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Nov  9 07:47:12 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Nov  9 07:47:12 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

09.11.21 07:48:28. 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-11-09 07:48:30 No severe PSQL-related problems occured
09.11.21 07:48:32. End of report.