Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.09.2021 04: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 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 15744596 kB
MemAvailable 30847020 kB
Buffers 20 kB
Cached 15863276 kB
SwapCached 336 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-09-04 04:37:07
DTS_END                         2021-09-04 04:37:07.4830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34488
TRN_ID                          229593
ATT_ID                          589
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: 9338.85 21587 1120662
customer order: creation 167.58 442 20109
customer order: refuse 14.31 371 1717
order to supplier: creation 44.42 1487 5330
order to supplier: removal 3.52 124317 422
invoice (draft): creation 45.26 3425 5431
invoice (draft): removal 52.81 957 6337
invoice accept: apply 41.81 20392 5017
invoice accept: cancel 47.77 957 5732
customer reserve: creation 3884.71 199 466165
customer reserve: removal 4750.85 73 570102
realization accept: apply 80.66 219 9679
realization accept: cancel 78.39 124 9407
payment from customer: creation 58.53 141 7023
payment from customer: removal 5.93 82 711
payment to supplier: creation 48.68 153 5842
payment to supplier: removal 3.18 60 382
service: total inventory turnovers 3.68 4222 441
service: total monetary turnovers 6.02 619 722
service: aggregate perf. data 0.74 23754 89
service: refresh index statistics 0.03 249755 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-09-2021 04:38:22 to 04-09-2021 04:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5586
TEST_TIME 2 5731
TEST_TIME 3 6307
TEST_TIME 4 6263
TEST_TIME 5 6001
TEST_TIME 6 6640
TEST_TIME 7 6157
TEST_TIME 8 6729
TEST_TIME 9 6627
TEST_TIME 10 6578
TEST_TIME 11 6465
TEST_TIME 12 6951
TEST_TIME 13 7008
TEST_TIME 14 6980
TEST_TIME 15 7045
TEST_TIME 16 7226
TEST_TIME 17 7282
TEST_TIME 18 7545
TEST_TIME 19 7922
TEST_TIME 20 8188
TEST_TIME 21 8149
TEST_TIME 22 8091
TEST_TIME 23 8770
TEST_TIME 24 9095
TEST_TIME 25 9165
TEST_TIME 26 9563
TEST_TIME 27 10220
TEST_TIME 28 10633
TEST_TIME 29 11015
TEST_TIME 30 10790
TEST_TIME 31 10648
TEST_TIME 32 10737
TEST_TIME 33 10545
TEST_TIME 34 10670
TEST_TIME 35 10777
TEST_TIME 36 10730
TEST_TIME 37 10667
TEST_TIME 38 10622
TEST_TIME 39 10632
TEST_TIME 40 10387
TEST_TIME 41 10293
TEST_TIME 42 10217
TEST_TIME 43 10081
TEST_TIME 44 10064
TEST_TIME 45 10038
TEST_TIME 46 9968
TEST_TIME 47 9871
TEST_TIME 48 9839
TEST_TIME 49 9807
TEST_TIME 50 9747
TEST_TIME 51 9712
TEST_TIME 52 9735
TEST_TIME 53 9824
TEST_TIME 54 10067
TEST_TIME 55 9955
TEST_TIME 56 9952
TEST_TIME 57 9971
TEST_TIME 58 9881
TEST_TIME 59 9986
TEST_TIME 60 9914
TEST_TIME 61 9939
TEST_TIME 62 10032
TEST_TIME 63 9971
TEST_TIME 64 10076
TEST_TIME 65 10075
TEST_TIME 66 10141
TEST_TIME 67 10001
TEST_TIME 68 9948
TEST_TIME 69 9860
TEST_TIME 70 9866
TEST_TIME 71 9867
TEST_TIME 72 9794
TEST_TIME 73 9807
TEST_TIME 74 9715
TEST_TIME 75 9646
TEST_TIME 76 9556
TEST_TIME 77 9540
TEST_TIME 78 9712
TEST_TIME 79 9700
TEST_TIME 80 9684
TEST_TIME 81 9701
TEST_TIME 82 9741
TEST_TIME 83 9788
TEST_TIME 84 9768
TEST_TIME 85 9802
TEST_TIME 86 9817
TEST_TIME 87 9807
TEST_TIME 88 9811
TEST_TIME 89 9744
TEST_TIME 90 9679
TEST_TIME 91 9673
TEST_TIME 92 9642
TEST_TIME 93 9579
TEST_TIME 94 9588
TEST_TIME 95 9551
TEST_TIME 96 9558
TEST_TIME 97 9535
TEST_TIME 98 9498
TEST_TIME 99 9503
TEST_TIME 100 9476
TEST_TIME 101 9590
TEST_TIME 102 9580
TEST_TIME 103 9554
TEST_TIME 104 9661
TEST_TIME 105 9695
TEST_TIME 106 9683
TEST_TIME 107 9703
TEST_TIME 108 9640
TEST_TIME 109 9615
TEST_TIME 110 9564
TEST_TIME 111 9572
TEST_TIME 112 9531
TEST_TIME 113 9499
TEST_TIME 114 9481
TEST_TIME 115 9410
TEST_TIME 116 9398
TEST_TIME 117 9428
TEST_TIME 118 9403
TEST_TIME 119 9417
TEST_TIME 120 9345

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-09-2021 04:38:25 to 04-09-2021 04:38:29.

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 1550120 1550120 0 0.00 0 27716 7 0 0
doc_list_biud 1550120 1550120 0 0.00 0 37052 34 0 0
sp_add_invoice_to_stock 5152 5017 135 2.62 20 272588 20393 0 135
sp_cancel_adding_invoice 5732 5732 0 0.00 6 76217 958 0 0
sp_cancel_client_order 1717 1717 0 0.00 4 23455 371 0 0
sp_cancel_customer_reserve 570102 570102 0 0.00 1 21437 74 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 8018 82 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 1324 61 0 0
sp_cancel_supplier_invoice 6353 6337 16 0.25 2 37065 957 0 16
sp_cancel_supplier_order 422 422 0 0.00 8 1297980 124318 0 0
sp_cancel_write_off 9407 9407 0 0.00 1 7467 125 0 0
sp_client_order 20109 20109 0 0.00 4 39016 442 0 0
sp_customer_reserve 466165 466165 0 0.00 0 150527 200 0 0
sp_fill_shopping_cart 30860 30860 0 0.00 1 11406 41 0 0
sp_fill_shopping_cart_clo_res 466157 466157 0 0.00 0 13141 16 0 0
sp_get_clo_for_invoice 5001 5001 0 0.00 0 34476 347 0 0
sp_kill_cost_storno 16232 16232 0 0.00 0 9633 66 0 0
sp_kill_qstorno_ret_qs2qd 1156370 1156370 0 0.00 0 37050 39 0 0
sp_kill_qty_storno 608396 608396 0 0.00 0 37051 84 0 0
sp_lock_selected_doc 20372 20372 0 0.00 0 8331 6 0 0
sp_make_cost_storno 27544 27544 0 0.00 0 15996 73 0 0
sp_multiply_rows_for_pdistr 27544 27544 0 0.00 0 10341 36 0 0
sp_multiply_rows_for_qdistr 20109 20109 0 0.00 0 26204 217 0 0
sp_pay_from_customer 7023 7023 0 0.00 4 17787 141 0 0
sp_pay_to_supplier 5842 5842 0 0.00 5 15820 153 0 0
sp_payment_common 12865 12865 0 0.00 1 11680 31 0 0
sp_qd_handle_on_cancel_clo 1717 1717 0 0.00 0 23444 342 0 0
sp_qd_handle_on_invoice_upd_sts 10732 10732 0 0.00 0 28128 381 0 0
sp_qd_handle_on_reserve_upd_sts 9679 9679 0 0.00 0 22938 67 0 0
sp_reserve_write_off 9679 9679 0 0.00 6 26230 219 0 0
sp_supplier_invoice 5431 5431 0 0.00 8 98779 3425 0 0
sp_supplier_order 5330 5330 0 0.00 6 72026 1488 0 0
srv_aggregate_perf_data 89 89 0 0.00 2749 350432 23755 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1369 148033 43532 0 0
srv_find_qd_qs_mism 1072288 1072288 0 0.00 0 27714 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 59 139993 4222 0 0
srv_make_money_saldo 722 722 0 0.00 1 16552 620 0 0
srv_recalc_idx_stat 4 4 0 0.00 165153 435959 249755 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5017 5017 0 0.00 0 24161 204 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 6639 152 0 0
t$perf-norm:sp_cancel_client_order 1717 1717 0 0.00 0 12532 30 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 4748 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 4624 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 4785 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 764 9 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 20813 1765 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 747 5 0 0
t$perf-norm:sp_client_order 20109 20109 0 0.00 0 19166 23 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 5929 22 0 0
t$perf-norm:sp_pay_from_customer 7023 7023 0 0.00 0 5616 10 0 0
t$perf-norm:sp_pay_to_supplier 5842 5842 0 0.00 0 8027 11 0 0
t$perf-norm:sp_reserve_write_off 9679 9679 0 0.00 0 16460 19 0 0
t$perf-norm:sp_supplier_invoice 5431 5431 0 0.00 0 12608 22 0 0
t$perf-norm:sp_supplier_order 5330 5330 0 0.00 0 4865 18 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 586 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 720 17 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 5745 27 0 0
t$perf-norm:srv_make_money_saldo 722 722 0 0.00 0 4610 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 10270 10000 270 2.62 0 5691 21 0 270
v_all_customers 36914 36914 0 0.00 0 79 1 0 0
v_all_suppliers 30098 30098 0 0.00 0 23 1 0 0
v_all_wares 40218 40218 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 2387 17 0 0
v_cancel_client_order 3434 3434 0 0.00 0 1948 8 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 1020 6 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 3039 7 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 253 4 0 0
v_cancel_supplier_invoice 806 774 32 3.97 0 4853 36 0 32
v_cancel_supplier_order 844 844 0 0.00 0 1198 6 0 0
v_cancel_write_off 2010 2010 0 0.00 0 2928 20 0 0
v_max_id_clo_ord 5330 5330 0 0.00 0 3173 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 700 5 0 0
v_max_id_ord_sup 5421 5421 0 0.00 0 10617 7 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 592 2 0 0
v_max_non_paid_realizn 3660 3660 0 0.00 0 5652 5 0 0
v_min_id_clo_ord 5330 5330 0 0.00 0 4131 5 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 6105 18 0 0
v_min_id_ord_sup 5421 5421 0 0.00 0 3496 8 0 0
v_min_non_paid_invoice 5842 5842 0 0.00 0 5855 21 0 0
v_min_non_paid_realizn 7023 7023 0 0.00 0 11675 25 0 0
v_our_firm 10030 10030 0 0.00 0 17 1 0 0
v_reserve_write_off 19358 19358 0 0.00 0 6159 7 0 0
x_lock_depdocs_on_canc_invoice 11682 11682 0 0.00 0 20791 135 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 3744 303 0 0
x_make_qstorno_1000_1200 5330 5330 0 0.00 2 71948 1403 0 0
x_make_qstorno_1200_2000 5431 5431 0 0.00 4 98770 3276 0 0
x_make_qstorno_2100_3300 466165 466165 0 0.00 0 150525 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-09-2021 04:38:30 to 04-09-2021 04:38:34.

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
02:07:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 32599 8192000000 9719050320 10015117312 794972784 8.845 100 87 174 0 824576672 8100624 21503264
02:09:48 26480 8192000000 9763098080 10070687744 837371408 9.273 100 86 172 1 870561008 12867264 20322336
02:11:22 17004 8192000000 9793978096 10113884160 868690768 9.587 100 82 164 0 907398704 19914256 18793680
02:12:44 15373 8192000000 9854617488 10187177984 917510512 10.072 100 69 138 2 962881888 29729840 15641536
02:14:02 10359 8192000000 9913567984 10257575936 956994288 10.460 100 84 168 1 1025825968 51480320 17351360
02:15:16 4771 8192000000 9966492352 10319171584 995851392 10.838 100 82 164 0 1087445488 75062992 16531104
02:16:24 2333 8192000000 9993601744 10355294208 1016847424 11.042 100 91 184 0 1110076464 74498592 18730448
02:17:30 1945 8192000000 10031375056 10404552704 1036443344 11.230 100 87 174 0 1149754528 96058160 17253024
02:18:34 2465 8192000000 10044663920 10412314624 1055237696 11.411 100 96 191 1 1149905360 74732352 19935312
02:19:37 2050 8192000000 10031752368 10405863424 1070743472 11.559 100 91 182 1 1143847136 54133520 18970144
02:20:41 5946 8192000000 9985495360 10370445312 1067927456 11.532 100 38 75 1 1094059216 17287296 8844464
02:21:48 5720 8192000000 9990250096 10385899520 1079566432 11.643 100 39 78 1 1102547488 14693488 8287568
02:22:55 10064 8192000000 10010037904 10410856448 1091219536 11.754 100 44 88 1 1130672592 30097680 9355376
02:24:07 13284 8192000000 10010430752 10399588352 1089499280 11.738 100 64 130 0 1112801712 8478320 14824112
02:25:21 10199 8192000000 10022848048 10431565824 1086909632 11.713 99 37 74 0 1126237648 31273696 8054320
02:26:33 7823 8192000000 9999987136 10401685504 1073033584 11.581 98 35 70 0 1101396656 20532160 7830912
02:27:46 19876 8192000000 10007419984 10421821440 1082668336 11.673 99 36 71 1 1121972144 31687088 7616720
02:29:08 8319 8192000000 9971944016 10373693440 1033645264 11.204 97 32 62 3 1059190496 18694432 6850800
02:30:20 7229 8192000000 9943922816 10336202752 985832400 10.741 95 41 79 1 1016842048 21911472 9098176
02:31:32 56280 8192000000 9934652848 10338226176 976282656 10.648 94 29 58 1 1000026224 18372368 5371200
02:33:30 7858 8192000000 9965359552 10354515968 1035006736 11.217 100 53 107 1 1066600176 20193808 11399632
02:34:39 5000 8192000000 9979945536 10366226432 1059717104 11.454 100 45 86 2 1088138608 19142400 9279104
02:35:45 7481 8192000000 9977171312 10376929280 1065368096 11.508 100 45 88 2 1099784832 23902064 10514672
02:36:57 70543 8192000000 10019905376 10419187712 1078725696 11.635 100 50 99 1 1122876816 34726560 9424560
02:39:14 9336 8192000000 10080166112 10479587328 1106796048 11.902 100 67 134 0 1188003808 67674752 13533008
02:40:53 11143 8192000000 10146314080 10544951296 1126700784 12.090 100 69 138 1 1251931664 111412592 13818288
02:42:16 15132 8192000000 10121969264 10527072256 1139236592 12.208 100 60 119 2 1228938032 76616896 13084544
02:43:40 8425 8192000000 10157187984 10587832320 1141067392 12.226 100 40 79 1 1262257616 112418016 8772208
02:44:53 16984 8192000000 10185659392 10594856960 1151045584 12.319 100 53 105 1 1289186304 127115632 11025088
02:46:15 1839 8192000000 10225348928 10629783552 1165578560 12.455 100 83 164 2 1328674592 145581296 17514736
02:47:20 7836 8192000000 10238769152 10655932416 1178349280 12.575 100 48 97 1 1343751072 154036896 11364896
02:48:30 8017 8192000000 10283720624 10702184448 1181869168 12.608 100 58 116 0 1389758864 194718736 13170960
02:49:45 4215 8192000000 10308343952 10720030720 1194078192 12.721 100 72 142 0 1406079552 196300112 15701248
02:50:54 2987 8192000000 10339202880 10757660672 1203696016 12.811 100 77 152 0 1445582480 225000752 16885712
02:52:07 1364 8192000000 10350882048 10766868480 1183683392 12.625 99 83 166 1 1439922320 236769040 19469888
02:53:13 4430 8192000000 10371327056 10801328128 1191772416 12.700 100 66 133 0 1463599328 256407872 15419040
02:54:21 2099 8192000000 10415960768 10825420800 1196009504 12.739 100 93 184 0 1515214496 296728864 22476128
02:55:29 1924 8192000000 10448095392 10860396544 1187009248 12.656 100 88 175 1 1545440208 338066208 20364752
02:56:36 2278 8192000000 10429970384 10841042944 1159810768 12.401 99 77 149 0 1524626128 347023136 17792224
02:57:47 5942 8192000000 10461435184 10877046784 1148635728 12.297 98 64 127 1 1539043488 375049792 15357968
02:59:06 3849 8192000000 10453888128 10864812032 1151298944 12.322 100 69 139 1 1552865920 385208512 16358464
03:00:22 12579 8192000000 10424952432 10872954880 1150013904 12.310 100 37 74 0 1540131024 379888848 10228272
03:01:39 14784 8192000000 10405507072 10861629440 1148043600 12.291 100 40 80 0 1526300912 368089680 10167632
03:03:08 24755 8192000000 10374250752 10879270912 1127916384 12.102 100 22 44 1 1503648800 369496960 6235456
03:04:57 31260 8192000000 10166546864 10643955712 1126311296 12.087 100 14 28 2 1338714000 208831440 3571264
03:06:31 54473 8192000000 10036929888 10470293504 1063922224 11.494 96 13 26 0 1097185424 31118160 2145040
03:08:40 79200 8192000000 10046614416 10470305792 1060958368 11.466 96 8 16 1 1110685424 47686240 2040816
03:11:00 73093 8192000000 10104892432 10574602240 1118341296 12.011 99 10 20 0 1180321264 59605792 2374176
03:13:14 59680 8192000000 10082408736 10531823616 1125901648 12.083 99 16 32 0 1156120224 26542256 3676320
03:15:16 68708 8192000000 10098279120 10580721664 1105958992 11.894 97 12 23 0 1179777632 71265776 2552864
03:17:28 91192 8192000000 10091235776 10525020160 1125422608 12.078 99 37 73 2 1161283984 28785584 7075792
03:20:00 45341 8192000000 10075242368 10514006016 1140914368 12.224 100 24 48 1 1172027840 25701792 5411680
03:21:46 40150 8192000000 10092251744 10543497216 1124547584 12.070 99 23 46 0 1196414032 66685248 5181200
03:23:28 39941 8192000000 10189730224 10649059328 1153515200 12.342 100 37 74 2 1265406256 104053584 7837472
03:25:19 148033 8192000000 10229799376 10744918016 1166509872 12.464 100 23 46 0 1303457120 131804720 5142528
03:28:52 100211 8192000000 10234812656 10712068096 1137856400 12.195 98 23 47 1 1343070672 199615520 5598752
03:31:33 59343 8192000000 10095278048 10548912128 1143168720 12.245 99 11 22 0 1202145536 56467760 2509056
03:33:34 76873 8192000000 10121654688 10597416960 1149320208 12.303 99 16 32 0 1214836688 62629952 2886528
03:35:55 72239 8192000000 10088203152 10544898048 1134534800 12.164 98 10 20 1 1181290096 44572208 2183088
03:38:11 87225 8192000000 10107569664 10630021120 1139436400 12.210 98 22 43 1 1206198288 62384608 4377280
03:40:56 33698 8192000000 10130553168 10618544128 1149716496 12.307 99 22 44 0 1231714352 77564752 4433104
03:42:32 59429 8192000000 10085871824 10590613504 1065282240 11.507 92 12 24 0 1131693008 64144160 2266608
03:44:37 64023 8192000000 10036440864 10484596736 1044406480 11.307 93 19 38 1 1065831792 17780304 3645008
03:46:47 54896 8192000000 10044236960 10499510272 1102485712 11.861 99 38 72 2 1171323632 61543552 7294368
03:48:48 5904 8192000000 10043429664 10498605056 1115898912 11.988 99 37 75 1 1136898576 13079648 7920016
03:49:57 36694 8192000000 10127675472 10583506944 1136008688 12.178 100 38 76 0 1228760400 84706336 8045376
03:51:43 55091 8192000000 10143668944 10621943808 1141638416 12.231 100 16 32 2 1243436128 97902912 3894800
03:53:52 74761 8192000000 10191914128 10677608448 1124250496 12.067 99 16 32 0 1274866128 146818272 3797360
03:56:08 32953 8192000000 10041894304 10470305792 1059395536 11.451 95 31 59 0 1083395760 16746912 7253312
03:57:42 38145 8192000000 10108483248 10543222784 1083038256 11.676 97 36 71 2 1178284528 87883904 7362368
03:59:21 81943 8192000000 10045597488 10528690176 1067570192 11.529 96 15 30 0 1158132272 87360784 3201296
04:02:10 27623 8192000000 10057323408 10526371840 1094505728 11.785 99 37 74 0 1171155488 69046512 7603248
04:03:38 55895 8192000000 10066386496 10532474880 1084427728 11.690 98 26 52 1 1148649872 58819584 5402560
04:05:35 9149 8192000000 10072710336 10492760064 1115242992 11.982 100 46 91 1 1161540848 37612624 8685232
04:06:50 100580 8192000000 10068628640 10519838720 1098255616 11.821 98 36 72 1 1185174832 79595680 7323536
04:09:31 51835 8192000000 10048481488 10503086080 1109973824 11.932 98 8 16 0 1141113360 29536656 1602880
04:11:23 35541 8192000000 10069626144 10558406656 1123691376 12.062 99 6 12 1 1168462832 43631968 1139488
04:13:01 52933 8192000000 10058902688 10540605440 1123903952 12.064 99 18 35 2 1169064432 40410112 4750368
04:15:02 48523 8192000000 10127522272 10570424320 1146155552 12.273 100 29 58 1 1208240432 55594848 6490032
04:16:53 73994 8192000000 10163643216 10647105536 1142104720 12.235 98 21 42 0 1246412016 99183056 5124240
04:19:15 85517 8192000000 10181611440 10637361152 1129488032 12.117 97 24 48 0 1195547776 61197824 4861920
04:21:44 41595 8192000000 10110025744 10592006144 1132441280 12.144 98 16 32 1 1212605952 76674768 3489904
04:23:27 17373 8192000000 10127232128 10587652096 1144529248 12.258 99 42 84 0 1216846032 64106768 8210016
04:24:45 130892 8192000000 10085740528 10536095744 1144135056 12.254 99 26 51 0 1198591056 48737040 5718960
04:27:57 47805 8192000000 10074842272 10560462848 1154670656 12.353 100 12 24 1 1195972912 38865664 2436592
04:29:46 61133 8192000000 10074414976 10540888064 1120815040 12.035 97 19 38 1 1165320864 40643456 3862368
04:31:48 44370 8192000000 10104738608 10571128832 1148342592 12.294 99 13 27 0 1203724176 52251824 3129760
04:33:34 57944 8192000000 10071410496 10587176960 1129023968 12.112 98 7 14 0 1203432016 73084832 1323216
04:35:32 35359 8192000000 10118488464 10562539520 1091990592 11.762 97 41 82 3 1168767056 68171856 8604608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-09-2021 04:38:36 to 04-09-2021 04:38:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-09-2021 04:38:52 to 04-09-2021 04:38:54.

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          228288
MON$OLDEST_ACTIVE               230649
MON$OLDEST_SNAPSHOT             230649
MON$NEXT_TRANSACTION            230649
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                       2075360
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2580 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2580 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2580 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2580 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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Sat Sep  4 04:37:09 2021
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Sat Sep  4 04:37:15 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
--- end of diff output ---

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

04.09.21 04:38:55. 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-09-04 04:38:57 No severe PSQL-related problems occured
04.09.21 04:38:59. End of report.