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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16354496 kB
MemAvailable 30999548 kB
Buffers 0 kB
Cached 15304952 kB
SwapCached 2592 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-08-10 10:37:06
DTS_END                         2021-08-10 10:37:06.6770
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/38690
TRN_ID                          232299
ATT_ID                          661
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: 8454.97 25334 1014596
customer order: creation 166.28 404 19954
customer order: refuse 15.23 422 1828
order to supplier: creation 44.85 1478 5382
order to supplier: removal 3.38 109251 405
invoice (draft): creation 44.63 3691 5356
invoice (draft): removal 46.08 1060 5529
invoice accept: apply 40.28 20696 4833
invoice accept: cancel 41.43 989 4971
customer reserve: creation 3720.73 203 446487
customer reserve: removal 4057.13 71 486855
realization accept: apply 81.43 193 9771
realization accept: cancel 69.36 142 8323
payment from customer: creation 57.22 114 6866
payment from customer: removal 6.13 100 736
payment to supplier: creation 47.11 143 5653
payment to supplier: removal 3.27 94 392
service: total inventory turnovers 3.63 3801 435
service: total monetary turnovers 6.04 597 725
service: aggregate perf. data 0.76 20118 91
service: refresh index statistics 0.03 343109 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-08-2021 10:38:24 to 10-08-2021 10:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5054
TEST_TIME 2 3573
TEST_TIME 3 3770
TEST_TIME 4 4722
TEST_TIME 5 5892
TEST_TIME 6 5470
TEST_TIME 7 5503
TEST_TIME 8 5540
TEST_TIME 9 5886
TEST_TIME 10 6305
TEST_TIME 11 6421
TEST_TIME 12 6406
TEST_TIME 13 6672
TEST_TIME 14 6843
TEST_TIME 15 6899
TEST_TIME 16 6855
TEST_TIME 17 7057
TEST_TIME 18 7588
TEST_TIME 19 7855
TEST_TIME 20 7993
TEST_TIME 21 8181
TEST_TIME 22 8309
TEST_TIME 23 8590
TEST_TIME 24 8894
TEST_TIME 25 8981
TEST_TIME 26 9007
TEST_TIME 27 9337
TEST_TIME 28 9591
TEST_TIME 29 10192
TEST_TIME 30 10135
TEST_TIME 31 9962
TEST_TIME 32 9723
TEST_TIME 33 9607
TEST_TIME 34 9473
TEST_TIME 35 9330
TEST_TIME 36 9217
TEST_TIME 37 9130
TEST_TIME 38 9184
TEST_TIME 39 9073
TEST_TIME 40 8976
TEST_TIME 41 9127
TEST_TIME 42 9034
TEST_TIME 43 8921
TEST_TIME 44 8729
TEST_TIME 45 8751
TEST_TIME 46 8665
TEST_TIME 47 8600
TEST_TIME 48 8599
TEST_TIME 49 8599
TEST_TIME 50 8552
TEST_TIME 51 8566
TEST_TIME 52 8537
TEST_TIME 53 8483
TEST_TIME 54 8702
TEST_TIME 55 8678
TEST_TIME 56 8703
TEST_TIME 57 8723
TEST_TIME 58 8825
TEST_TIME 59 8799
TEST_TIME 60 8858
TEST_TIME 61 8827
TEST_TIME 62 8727
TEST_TIME 63 8651
TEST_TIME 64 8669
TEST_TIME 65 8586
TEST_TIME 66 8606
TEST_TIME 67 8560
TEST_TIME 68 8598
TEST_TIME 69 8557
TEST_TIME 70 8640
TEST_TIME 71 8618
TEST_TIME 72 8603
TEST_TIME 73 8653
TEST_TIME 74 8596
TEST_TIME 75 8634
TEST_TIME 76 8733
TEST_TIME 77 8755
TEST_TIME 78 8803
TEST_TIME 79 8833
TEST_TIME 80 8735
TEST_TIME 81 8657
TEST_TIME 82 8632
TEST_TIME 83 8575
TEST_TIME 84 8521
TEST_TIME 85 8466
TEST_TIME 86 8482
TEST_TIME 87 8479
TEST_TIME 88 8436
TEST_TIME 89 8434
TEST_TIME 90 8494
TEST_TIME 91 8489
TEST_TIME 92 8520
TEST_TIME 93 8565
TEST_TIME 94 8591
TEST_TIME 95 8636
TEST_TIME 96 8628
TEST_TIME 97 8667
TEST_TIME 98 8672
TEST_TIME 99 8676
TEST_TIME 100 8702
TEST_TIME 101 8664
TEST_TIME 102 8613
TEST_TIME 103 8582
TEST_TIME 104 8570
TEST_TIME 105 8561
TEST_TIME 106 8524
TEST_TIME 107 8496
TEST_TIME 108 8492
TEST_TIME 109 8486
TEST_TIME 110 8458
TEST_TIME 111 8464
TEST_TIME 112 8467
TEST_TIME 113 8434
TEST_TIME 114 8481
TEST_TIME 115 8533
TEST_TIME 116 8557
TEST_TIME 117 8536
TEST_TIME 118 8506
TEST_TIME 119 8528
TEST_TIME 120 8458

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-08-2021 10:38:27 to 10-08-2021 10:38:31.

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 1427609 1427609 0 0.00 0 16102 7 0 0
doc_list_biud 1427609 1427609 0 0.00 0 34053 31 0 0
sp_add_invoice_to_stock 4948 4833 115 2.32 40 227299 20697 0 115
sp_cancel_adding_invoice 4971 4971 0 0.00 5 61584 989 0 0
sp_cancel_client_order 1828 1828 0 0.00 5 9186 423 0 0
sp_cancel_customer_reserve 486855 486855 0 0.00 1 34055 72 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 4874 101 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 7376 94 0 0
sp_cancel_supplier_invoice 5537 5529 8 0.14 2 14827 1060 0 8
sp_cancel_supplier_order 405 405 0 0.00 9 1116684 109252 0 0
sp_cancel_write_off 8323 8323 0 0.00 1 13847 142 0 0
sp_client_order 19954 19954 0 0.00 4 20920 404 0 0
sp_customer_reserve 446487 446487 0 0.00 0 49259 204 0 0
sp_fill_shopping_cart 30674 30674 0 0.00 1 17383 50 0 0
sp_fill_shopping_cart_clo_res 446485 446485 0 0.00 0 19258 19 0 0
sp_get_clo_for_invoice 4830 4830 0 0.00 0 39373 447 0 0
sp_kill_cost_storno 14422 14422 0 0.00 0 13838 67 0 0
sp_kill_qstorno_ret_qs2qd 987967 987967 0 0.00 0 22442 39 0 0
sp_kill_qty_storno 522512 522512 0 0.00 0 34053 85 0 0
sp_lock_selected_doc 20428 20428 0 0.00 0 11464 6 0 0
sp_make_cost_storno 27120 27120 0 0.00 0 16098 62 0 0
sp_multiply_rows_for_pdistr 27120 27120 0 0.00 0 8205 35 0 0
sp_multiply_rows_for_qdistr 19954 19954 0 0.00 0 20857 220 0 0
sp_pay_from_customer 6866 6866 0 0.00 4 10421 114 0 0
sp_pay_to_supplier 5653 5653 0 0.00 5 13596 143 0 0
sp_payment_common 12519 12519 0 0.00 1 7587 23 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 9178 375 0 0
sp_qd_handle_on_invoice_upd_sts 9801 9801 0 0.00 0 32950 380 0 0
sp_qd_handle_on_reserve_upd_sts 9771 9771 0 0.00 0 15588 54 0 0
sp_reserve_write_off 9771 9771 0 0.00 7 24350 193 0 0
sp_supplier_invoice 5356 5356 0 0.00 8 89000 3692 0 0
sp_supplier_order 5382 5382 0 0.00 6 42045 1478 0 0
srv_aggregate_perf_data 91 91 0 0.00 2775 343044 20118 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1291 126143 41981 0 0
srv_find_qd_qs_mism 968246 968246 0 0.00 0 14432 6 0 0
srv_make_invnt_saldo 435 435 0 0.00 49 97347 3802 0 0
srv_make_money_saldo 725 725 0 0.00 1 14011 597 0 0
srv_recalc_idx_stat 4 4 0 0.00 234386 558767 343109 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4833 4833 0 0.00 0 8093 160 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 1594 88 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 2288 15 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 1040 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 1439 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 4303 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2058 19 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 21473 1578 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 5549 27 0 0
t$perf-norm:sp_client_order 19954 19954 0 0.00 0 8003 21 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 1058 5 0 0
t$perf-norm:sp_pay_from_customer 6866 6866 0 0.00 0 4462 8 0 0
t$perf-norm:sp_pay_to_supplier 5653 5653 0 0.00 0 7865 11 0 0
t$perf-norm:sp_reserve_write_off 9771 9771 0 0.00 0 8053 12 0 0
t$perf-norm:sp_supplier_invoice 5356 5356 0 0.00 0 3485 15 0 0
t$perf-norm:sp_supplier_order 5382 5382 0 0.00 0 4421 16 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 734 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 1940 11 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 490 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9888 9658 230 2.32 0 11733 21 0 230
v_all_customers 35814 35814 0 0.00 0 81 1 0 0
v_all_suppliers 29514 29514 0 0.00 0 17 1 0 0
v_all_wares 39906 39906 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 5611 14 0 0
v_cancel_client_order 3656 3656 0 0.00 0 4909 9 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 4826 12 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 6109 13 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 5216 16 0 0
v_cancel_supplier_invoice 836 820 16 1.91 0 6671 38 0 16
v_cancel_supplier_order 810 810 0 0.00 0 2519 13 0 0
v_cancel_write_off 1996 1996 0 0.00 0 3063 17 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 1279 2 0 0
v_max_id_clo_res 930 930 0 0.00 0 7395 32 0 0
v_max_id_ord_sup 5342 5342 0 0.00 0 6005 6 0 0
v_max_non_paid_invoice 1617 1617 0 0.00 0 424 2 0 0
v_max_non_paid_realizn 3916 3916 0 0.00 0 658 2 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 12219 17 0 0
v_min_id_clo_res 930 930 0 0.00 0 3311 8 0 0
v_min_id_ord_sup 5342 5342 0 0.00 0 11370 10 0 0
v_min_non_paid_invoice 5653 5653 0 0.00 0 7318 17 0 0
v_min_non_paid_realizn 6866 6866 0 0.00 0 6841 16 0 0
v_our_firm 9992 9992 0 0.00 0 19 1 0 0
v_reserve_write_off 19542 19542 0 0.00 0 4022 6 0 0
x_lock_depdocs_on_canc_invoice 10090 10090 0 0.00 0 19380 121 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 4686 345 0 0
x_make_qstorno_1000_1200 5382 5382 0 0.00 2 42035 1382 0 0
x_make_qstorno_1200_2000 5356 5356 0 0.00 3 88991 3502 0 0
x_make_qstorno_2100_3300 446487 446487 0 0.00 0 46262 182 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-08-2021 10:38:32 to 10-08-2021 10:38:36.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:10 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 30264 8192000000 9728453616 10028838912 816432064 9.062 100 91 182 0 845748288 7683216 21633008
08:09:44 21054 8192000000 9753518608 10058936320 828985680 9.189 100 90 180 0 863862048 13651952 21224416
08:11:12 14429 8192000000 9796185536 10111668224 861244672 9.513 100 83 166 0 905282784 25433376 18604736
08:12:34 17296 8192000000 9884049952 10214551552 909981760 9.997 100 87 174 2 989648704 60971648 18695296
08:13:56 11140 8192000000 9911554480 10252488704 942129328 10.314 100 78 156 0 1023721840 64856176 16736336
08:15:10 5193 8192000000 9951744608 10298609664 988178736 10.764 100 85 170 1 1058994384 53633728 17181920
08:16:18 3154 8192000000 9982961952 10339471360 1011904000 10.994 100 86 171 1 1092617808 63267840 17445968
08:17:23 1738 8192000000 10030474576 10393047040 1033410784 11.201 100 95 189 1 1140892656 87767712 19714160
08:18:26 1889 8192000000 10041390688 10409164800 1064789488 11.502 100 98 196 0 1153997440 67717600 21490352
08:19:28 2748 8192000000 10018003344 10397597696 1061472832 11.471 100 68 131 0 1127609680 52166224 13970624
08:20:32 10205 8192000000 9989129328 10383319040 1070326816 11.555 100 35 71 1 1096682800 19015952 7340032
08:21:46 7993 8192000000 9991939504 10396172288 1081373968 11.661 100 34 66 2 1115578736 27229136 6975632
08:22:55 7666 8192000000 9993801696 10398175232 1084031056 11.686 100 44 86 0 1110190560 17397728 8761776
08:24:05 56038 8192000000 10001442432 10421186560 1087992640 11.724 100 32 62 1 1107208880 13172192 6044048
08:26:02 9546 8192000000 10032189904 10445557760 1094833472 11.789 100 32 64 0 1133296320 31948480 6514368
08:27:12 8753 8192000000 10014648896 10424664064 1065547248 11.510 98 45 93 3 1102862704 27428816 9886640
08:28:27 7348 8192000000 9994429536 10395381760 1060771152 11.464 99 31 62 0 1090885008 23460272 6653584
08:29:42 15507 8192000000 9953113664 10319716352 982902960 10.712 94 64 129 0 1011638336 12977072 15758304
08:30:59 6929 8192000000 9950681840 10335014912 994367104 10.824 97 54 107 2 1032063360 26686704 11009552
08:32:08 10638 8192000000 9933914928 10319241216 993139792 10.812 97 27 55 0 1011802288 12713664 5948832
08:33:19 16823 8192000000 9956355040 10356543488 1043543888 11.299 100 25 50 2 1070763664 20938448 6281328
08:34:41 25274 8192000000 9954926800 10363150336 1039900512 11.264 99 21 40 2 1076883904 32905216 4078176
08:36:08 5440 8192000000 9975694640 10367905792 1063491776 11.490 100 33 64 2 1096695440 26066112 7137552
08:37:17 30076 8192000000 10003348144 10394828800 1083425808 11.680 100 44 88 2 1103842304 10919680 9496816
08:38:53 84879 8192000000 10077557920 10501054464 1107555472 11.909 100 43 86 1 1189550320 73136496 8858352
08:41:26 39070 8192000000 10106057696 10521169920 1119658176 12.024 100 36 72 0 1220707792 93052656 7996960
08:43:18 7739 8192000000 10110898928 10515656704 1136633472 12.184 100 68 136 2 1216034496 64004096 15396928
08:44:30 15130 8192000000 10172327648 10592894976 1144347792 12.256 100 59 118 1 1291020304 133780656 12891856
08:45:49 11021 8192000000 10182793024 10594988032 1142924048 12.243 100 66 128 2 1278392000 121180736 14287216
08:47:06 8870 8192000000 10190013536 10622455808 1158659136 12.391 100 51 103 2 1300152656 130212336 11281184
08:48:20 11707 8192000000 10219688528 10645458944 1168408512 12.482 100 61 119 2 1317604864 136255200 12941152
08:49:38 7624 8192000000 10253442800 10662506496 1168342160 12.481 100 79 154 2 1353829456 167761312 17725984
08:50:52 3097 8192000000 10249781376 10674069504 1175683120 12.550 100 52 104 0 1349340672 161929904 11727648
08:52:01 2620 8192000000 10277998944 10687574016 1170511248 12.502 100 77 152 0 1380758512 193049216 17198048
08:53:13 7027 8192000000 10282719216 10688237568 1141379632 12.229 99 67 136 1 1372446288 215834672 15231984
08:54:25 2503 8192000000 10346029392 10762100736 1151186672 12.321 100 92 182 0 1438723008 266581104 20955232
08:55:32 1291 8192000000 10325702656 10732396544 1152011424 12.328 100 77 153 0 1428028672 257625904 18391344
08:56:41 3103 8192000000 10290147120 10700148736 1150532592 12.314 100 79 158 1 1382785984 214697296 17556096
08:57:47 7701 8192000000 10315288832 10737274880 1151712624 12.326 100 49 95 1 1420579184 257386512 11480048
08:59:07 3949 8192000000 10337674816 10753826816 1148515424 12.296 100 64 128 1 1433722080 270452784 14753872
09:00:33 12905 8192000000 10301773904 10741272576 1146255216 12.274 100 36 72 1 1397146352 241232624 9658512
09:01:48 9449 8192000000 10277435536 10721632256 1151364416 12.322 100 49 96 1 1372986016 209795920 11825680
09:03:02 14459 8192000000 10283264896 10719047680 1113207680 11.963 97 28 56 1 1352816544 232559680 7049184
09:04:19 16922 8192000000 10292608080 10741506048 1126348208 12.087 100 22 44 0 1399846032 267554656 5943168
09:05:46 11631 8192000000 10193242608 10628444160 1121788016 12.044 100 31 62 0 1299450064 170641648 7020400
09:07:01 16892 8192000000 10084913136 10519257088 1117099456 12.000 100 56 112 1 1166637152 38332656 11205040
09:08:20 97423 8192000000 10047432608 10508140544 1114635856 11.976 99 22 44 0 1145521248 25962784 4922608
09:11:00 75749 8192000000 10090746944 10526908416 1127322752 12.096 99 29 57 1 1178109392 45389312 5397328
09:13:21 61074 8192000000 10112607536 10562400256 1121064560 12.037 98 26 52 1 1199105056 72748624 5291872
09:15:23 64933 8192000000 10103386304 10543304704 1132877104 12.148 99 17 34 0 1184182464 47630080 3675280
09:17:29 62824 8192000000 10098159664 10572832768 1131124752 12.132 98 21 41 0 1232554528 96651040 4778736
09:19:33 77534 8192000000 10079059376 10552606720 1116268528 11.992 98 20 40 0 1172314800 51395664 4650608
09:21:55 23105 8192000000 10088592528 10551623680 1127973008 12.102 99 24 48 0 1177713424 44920208 4820208
09:23:25 45869 8192000000 10128441744 10588315648 1147482656 12.286 100 25 51 1 1226881248 74186608 5211984
09:25:14 46760 8192000000 10095139152 10588741632 1144824608 12.261 100 17 34 1 1213289600 64529392 3935600
09:27:02 29609 8192000000 10097185584 10536845312 1135643504 12.175 99 35 70 3 1184342960 41411056 7288400
09:28:36 50520 8192000000 10117121344 10558005248 1132281552 12.143 99 24 48 0 1181567696 44149248 5136896
09:30:28 28843 8192000000 10160957968 10610163712 1137012960 12.187 99 25 50 2 1251408640 108867728 5527952
09:32:03 34650 8192000000 10126743936 10579046400 1144089344 12.254 100 16 31 2 1228250144 80953104 3207696
09:33:39 66548 8192000000 10134813600 10574696448 1116014992 11.989 97 19 38 1 1233329744 113101920 4212832
09:35:50 12176 8192000000 10069646432 10493952000 1134889088 12.167 99 59 118 0 1165286560 17610848 12786624
09:37:02 19927 8192000000 10121927216 10547101696 1135561504 12.174 99 43 86 1 1212702848 68793344 8348000
09:38:26 82498 8192000000 10081703632 10520768512 1128885264 12.111 99 43 85 2 1179492624 41777648 8829712
09:41:14 40465 8192000000 10067431056 10543136768 1133992448 12.159 99 18 36 0 1168778112 31344960 3440704
09:42:55 108387 8192000000 10083438208 10560061440 1109577520 11.928 97 11 22 1 1165596768 53454832 2564416
09:45:44 57289 8192000000 10060854416 10508951552 1072878496 11.580 94 20 40 0 1119639696 42666688 4094512
09:47:43 67871 8192000000 10052227056 10503032832 1105161936 11.887 98 12 23 1 1136702320 28962016 2578368
09:49:52 67147 8192000000 10067707552 10544799744 1112345584 11.955 99 26 52 1 1165281920 47944752 4991584
09:52:00 82345 8192000000 10079916832 10605125632 1089167952 11.735 96 21 42 1 1175342640 81853056 4321632
09:54:23 63157 8192000000 10072158736 10528378880 1027736896 11.147 92 25 50 1 1083642832 51027152 4878784
09:56:29 126143 8192000000 10035783024 10536910848 1084848768 11.694 98 11 22 0 1130942896 43850352 2243776
09:59:35 42640 8192000000 10035543808 10491518976 1104757824 11.883 99 4 8 0 1140891440 35173312 960304
10:01:44 60478 8192000000 10064374544 10520977408 1118588272 12.014 100 15 30 2 1170739392 48596240 3554880
10:04:03 55145 8192000000 10066899968 10542768128 1111802960 11.949 99 12 24 0 1162370000 48189600 2377440
10:06:03 79722 8192000000 10080226304 10586124288 1108272336 11.916 98 14 29 1 1191343232 80405520 2665376
10:08:32 82867 8192000000 10126683504 10610991104 1142887152 12.243 100 23 46 1 1242748144 94802064 5058928
10:11:04 56765 8192000000 10110077136 10568364032 1144645216 12.259 100 21 42 0 1212932384 64002656 4284512
10:13:06 49737 8192000000 10104280336 10554257408 1126081520 12.084 98 37 74 1 1186246144 52957936 7206688
10:14:59 85723 8192000000 10119635088 10606055424 1120976080 12.036 98 16 32 0 1173816000 49378304 3461616
10:17:59 16628 8192000000 10091991968 10511331328 1136154480 12.179 100 56 109 1 1184114688 36758976 11201232
10:19:17 86251 8192000000 10076477104 10517901312 1118758560 12.015 98 34 67 0 1178962256 53005616 7198080
10:22:01 34525 8192000000 10072820320 10543726592 1139865472 12.214 100 14 28 0 1189118880 46352672 2900736
10:24:50 44018 8192000000 10066182912 10539175936 1116336032 11.992 98 12 24 0 1162546640 43808016 2402592
10:26:35 46074 8192000000 10048871712 10490847232 1094957680 11.790 96 18 36 0 1123101008 23753872 4389456
10:28:21 50886 8192000000 10106740192 10557112320 1115693376 11.986 98 30 60 0 1171312496 49293712 6325408
10:30:13 40163 8192000000 10141515968 10570788864 1119152032 12.019 98 42 82 1 1229619696 101618880 8848784
10:32:01 71361 8192000000 10075004672 10562342912 1119521088 12.022 99 16 32 0 1198678656 75657072 3500496
10:34:38 28411 8192000000 10103517632 10543194112 1106142064 11.896 98 32 64 0 1182444496 69950416 6352016
10:36:08 16692 8192000000 10141675040 10597232640 1118956304 12.017 99 44 85 0 1243964640 115734352 9273984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-08-2021 10:38:38 to 10-08-2021 10:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-08-2021 10:38:54 to 10-08-2021 10:38:56.

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          228607
MON$OLDEST_ACTIVE               232704
MON$OLDEST_SNAPSHOT             232704
MON$NEXT_TRANSACTION            232704
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                       2029280
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2567 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2567 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2567 Firebird 4.0/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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Aug 10 10:37:08 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Tue Aug 10 10:37:27 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Tue Aug 10 10:37:27 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Tue Aug 10 10:37:29 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
--- end of diff output ---

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

10.08.21 10:38:57. 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-08-10 10:38:59 No severe PSQL-related problems occured
10.08.21 10:39:01. End of report.